You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/08/14 11:46:18 UTC
[JENKINS-EA] Lucene-Solr-5.3-Linux (64bit/jdk1.9.0-ea-b60) - Build
# 61 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.3-Linux/61/
Java: 64bit/jdk1.9.0-ea-b60 -XX:+UseCompressedOops -XX:+UseParallelGC -Djava.locale.providers=JRE,SPI
1 tests failed.
FAILED: org.apache.solr.util.TestSolrCLIRunExample.testTechproductsExample
Error Message:
expected 32 docs in the techproducts example but found 0, output: Creating Solr home directory /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr Starting up Solr on port 46362 using command: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/bin/solr start -p 46362 -s "temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr" Setup new core instance directory: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts Creating new core 'techproducts' using command: http://localhost:46362/solr/admin/cores?action=CREATE&name=techproducts&instanceDir=techproducts { "responseHeader":{ "status":0, "QTime":180}, "core":"techproducts"} Indexing tech product example docs from /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/example/exampledocs Solr techproducts example launched successfully. Direct your Web browser to http://localhost:46362/solr to visit the Solr Admin UI
Stack Trace:
java.lang.AssertionError: expected 32 docs in the techproducts example but found 0, output: Creating Solr home directory /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr
Starting up Solr on port 46362 using command:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/bin/solr start -p 46362 -s "temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr"
Setup new core instance directory:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts
Creating new core 'techproducts' using command:
http://localhost:46362/solr/admin/cores?action=CREATE&name=techproducts&instanceDir=techproducts
{
"responseHeader":{
"status":0,
"QTime":180},
"core":"techproducts"}
Indexing tech product example docs from /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/example/exampledocs
Solr techproducts example launched successfully. Direct your Web browser to http://localhost:46362/solr to visit the Solr Admin UI
at __randomizedtesting.SeedInfo.seed([E20D15BD8E30B620:68DAC50594D28EBF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.util.TestSolrCLIRunExample.testExample(TestSolrCLIRunExample.java:357)
at org.apache.solr.util.TestSolrCLIRunExample.testTechproductsExample(TestSolrCLIRunExample.java:284)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:502)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11501 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/init-core-data-001
[junit4] 2> 1476056 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 1476075 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1476076 INFO (Thread-5387) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1476076 INFO (Thread-5387) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1476176 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.ZkTestServer start zk server on port:53839
[junit4] 2> 1476176 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1476176 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476178 INFO (zkCallback-1467-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@47b06193 name:ZooKeeperConnection Watcher:127.0.0.1:53839 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476178 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476178 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1476179 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1476180 INFO (jetty-launcher-1466-thread-1) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1476181 INFO (jetty-launcher-1466-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d9bc854{/solr,null,AVAILABLE}
[junit4] 2> 1476181 INFO (jetty-launcher-1466-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@92be996{HTTP/1.1}{127.0.0.1:46702}
[junit4] 2> 1476182 INFO (jetty-launcher-1466-thread-1) [ ] o.e.j.s.Server Started @1477695ms
[junit4] 2> 1476182 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46702}
[junit4] 2> 1476182 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@759ebb3d
[junit4] 2> 1476182 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/'
[junit4] 2> 1476192 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1476192 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476193 INFO (zkCallback-1468-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7fb3f666 name:ZooKeeperConnection Watcher:127.0.0.1:53839/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476193 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476193 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1476193 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1476196 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002
[junit4] 2> 1476196 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 388780282
[junit4] 2> 1476196 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/]
[junit4] 2> 1476197 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/lib
[junit4] 2> 1476197 WARN (jetty-launcher-1466-thread-1) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/lib).
[junit4] 2> 1476200 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1476201 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1476201 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1476201 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1476201 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53839/solr
[junit4] 2> 1476202 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1476202 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1476202 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476202 INFO (zkCallback-1470-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5ee56a15 name:ZooKeeperConnection Watcher:127.0.0.1:53839 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476203 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476203 INFO (jetty-launcher-1466-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1476204 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476204 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6621deba name:ZooKeeperConnection Watcher:127.0.0.1:53839/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476204 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476205 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1476206 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1476206 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1476207 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1476207 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1476208 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1476208 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1476209 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1476209 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1476209 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1476210 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:46702_solr
[junit4] 2> 1476210 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/localhost:46702_solr
[junit4] 2> 1476210 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1476211 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1476211 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1476211 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94342054463668227-localhost:46702_solr-n_0000000000
[junit4] 2> 1476212 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:46702_solr
[junit4] 2> 1476212 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1476212 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.Overseer Overseer (id=94342054463668227-localhost:46702_solr-n_0000000000) starting
[junit4] 2> 1476213 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1476214 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1476215 INFO (OverseerCollectionProcessor-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
[junit4] 2> 1476215 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1476215 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1476226 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1476226 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1476226 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002
[junit4] 2> 1476227 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.c.CoresLocator Found 0 core definitions
[junit4] 2> 1476227 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1476228 INFO (jetty-launcher-1466-thread-1) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1476228 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1476228 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476229 INFO (zkCallback-1472-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@209d985 name:ZooKeeperConnection Watcher:127.0.0.1:53839 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476229 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476229 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1476239 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=6
[junit4] 2> 1476242 INFO (qtp768125803-12328) [n:localhost:46702_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json
[junit4] 2> 1476243 INFO (qtp768125803-12328) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 1476243 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1476244 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476244 INFO (zkCallback-1475-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5fdfc7d5 name:ZooKeeperConnection Watcher:127.0.0.1:53839/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476245 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476245 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1476245 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1476247 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json
[junit4] 2> 1476247 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1476253 INFO (qtp768125803-12329) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=4
[junit4] 2> 1476256 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json
[junit4] 2> 1476256 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 1476257 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1476257 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1476257 INFO (zkCallback-1477-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24e32c60 name:ZooKeeperConnection Watcher:127.0.0.1:53839/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1476258 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1476258 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1476258 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1476259 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/protwords.txt
[junit4] 2> 1476261 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/solrconfig.xml
[junit4] 2> 1476262 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/stopwords.txt
[junit4] 2> 1476262 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ar.txt
[junit4] 2> 1476263 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_el.txt
[junit4] 2> 1476264 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ja.txt
[junit4] 2> 1476265 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_gl.txt
[junit4] 2> 1476265 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_fr.txt
[junit4] 2> 1476266 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fr.txt
[junit4] 2> 1476266 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_nl.txt
[junit4] 2> 1476267 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_cz.txt
[junit4] 2> 1476268 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ca.txt
[junit4] 2> 1476268 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_de.txt
[junit4] 2> 1476269 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ga.txt
[junit4] 2> 1476269 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_da.txt
[junit4] 2> 1476270 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_no.txt
[junit4] 2> 1476271 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_es.txt
[junit4] 2> 1476271 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_sv.txt
[junit4] 2> 1476272 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fi.txt
[junit4] 2> 1476273 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hu.txt
[junit4] 2> 1476273 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_bg.txt
[junit4] 2> 1476274 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fa.txt
[junit4] 2> 1476274 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_it.txt
[junit4] 2> 1476275 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_eu.txt
[junit4] 2> 1476276 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ro.txt
[junit4] 2> 1476276 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ga.txt
[junit4] 2> 1476277 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stemdict_nl.txt
[junit4] 2> 1476277 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hy.txt
[junit4] 2> 1476278 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ca.txt
[junit4] 2> 1476279 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stoptags_ja.txt
[junit4] 2> 1476280 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_lv.txt
[junit4] 2> 1476280 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/userdict_ja.txt
[junit4] 2> 1476281 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_tr.txt
[junit4] 2> 1476281 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_it.txt
[junit4] 2> 1476282 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ru.txt
[junit4] 2> 1476283 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_th.txt
[junit4] 2> 1476283 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/hyphenations_ga.txt
[junit4] 2> 1476284 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_en.txt
[junit4] 2> 1476284 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hi.txt
[junit4] 2> 1476285 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_pt.txt
[junit4] 2> 1476285 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_id.txt
[junit4] 2> 1476286 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/synonyms.txt
[junit4] 2> 1476287 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/elevate.xml
[junit4] 2> 1476287 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/currency.xml
[junit4] 2> 1476288 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/params.json
[junit4] 2> 1476288 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E20D15BD8E30B620]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1476290 INFO (qtp768125803-12329) [n:localhost:46702_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json
[junit4] 2> 1476290 INFO (qtp768125803-12329) [n:localhost:46702_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1476291 INFO (qtp768125803-12328) [n:localhost:46702_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json
[junit4] 2> 1476292 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/collection-queue-work state SyncConnected
[junit4] 2> 1476292 INFO (OverseerCollectionProcessor-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1476293 WARN (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor OverseerCollectionProcessor.processMessage : create , {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1476293 WARN (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1476293 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor creating collections conf node /collections/testCloudExamplePrompt
[junit4] 2> 1476294 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testCloudExamplePrompt
[junit4] 2> 1476294 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1476295 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1476295 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCloudExamplePrompt
[junit4] 2> 1476296 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCloudExamplePrompt/state.json
[junit4] 2> 1476296 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1476296 INFO (zkCallback-1477-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1476296 INFO (zkCallback-1477-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
[junit4] 2> 1476296 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
[junit4] 2> 1476395 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Creating SolrCores for new collection testCloudExamplePrompt, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 1476395 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard2_replica2 as part of shard shard2 of collection testCloudExamplePrompt on localhost:46702_solr
[junit4] 2> 1476395 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard1_replica1 as part of shard shard1 of collection testCloudExamplePrompt on localhost:46702_solr
[junit4] 2> 1476397 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard1_replica2 as part of shard shard1 of collection testCloudExamplePrompt on localhost:46702_solr
[junit4] 2> 1476397 INFO (qtp768125803-12329) [n:localhost:46702_solr ] o.a.s.h.a.CoreAdminHandler core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1476397 INFO (OverseerThreadFactory-5804-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard2_replica1 as part of shard shard2 of collection testCloudExamplePrompt on localhost:46702_solr
[junit4] 2> 1476397 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.h.a.CoreAdminHandler core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1476398 INFO (qtp768125803-12329) [n:localhost:46702_solr ] o.a.s.c.SolrCore Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica2, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=testCloudExamplePrompt_shard2_replica2, collection=testCloudExamplePrompt, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 1476398 INFO (qtp768125803-12327) [n:localhost:46702_solr ] o.a.s.c.SolrCore Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=testCloudExamplePrompt_shard1_replica1, collection=testCloudExamplePrompt, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 1476398 INFO (qtp768125803-12363) [n:localhost:46702_solr ] o.a.s.h.a.CoreAdminHandler core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1476398 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1476398 INFO (qtp768125803-12365) [n:localhost:46702_solr ] o.a.s.h.a.CoreAdminHandler core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1476399 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1476398 INFO (qtp768125803-12363) [n:localhost:46702_solr ] o.a.s.c.SolrCore Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica2, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=testCloudExamplePrompt_shard1_replica2, collection=testCloudExamplePrompt, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 1476399 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 1476399 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1476399 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 1476399 INFO (qtp768125803-12365) [n:localhost:46702_solr ] o.a.s.c.SolrCore Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=testCloudExamplePrompt_shard2_replica1, collection=testCloudExamplePrompt, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 1476399 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 1
[junit4] 2> 1476399 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1476399 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader addZkWatch testCloudExamplePrompt
[junit4] 2> 1476400 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1476400 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Updating collection state at /collections/testCloudExamplePrompt/state.json from ZooKeeper...
[junit4] 2> 1476400 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1476400 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 1476400 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Updating data for testCloudExamplePrompt to ver 0
[junit4] 2> 1476400 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1476400 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 1476401 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 1
[junit4] 2> 1476401 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1476401 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1476400 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1476400 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1476401 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1476402 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 1
[junit4] 2> 1476402 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1476403 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 1
[junit4] 2> 1476403 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:46702/solr",
[junit4] 2> "node_name":"localhost:46702_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1476404 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1476404 INFO (OverseerStateUpdate-94342054463668227-localhost:46702_solr-n_0000000000) [n:localhost:46702_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCloudExamplePrompt/state.json version: 0
[junit4] 2> 1476404 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json for collection testCloudExamplePrompt has occurred - updating... (live nodes size: 1)
[junit4] 2> 1476404 INFO (zkCallback-1471-thread-1-processing-n:localhost:46702_solr) [n:localhost:46702_solr ] o.a.s.c.c.ZkStateReader Updating data for testCloudExamplePrompt to ver 1
[junit4] 2> 1477401 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica2
[junit4] 2> 1477401 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica1
[junit4] 2> 1477401 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1477401 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica2
[junit4] 2> 1477401 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1477401 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 1477401 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1477401 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1477402 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1477402 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1477402 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 1477401 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica1
[junit4] 2> 1477402 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 1477402 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader path=/collections/testCloudExamplePrompt configName=testCloudExamplePrompt specified config exists in ZooKeeper
[junit4] 2> 1477402 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader path=/collections/testCloudExamplePrompt configName=testCloudExamplePrompt specified config exists in ZooKeeper
[junit4] 2> 1477403 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader path=/collections/testCloudExamplePrompt configName=testCloudExamplePrompt specified config exists in ZooKeeper
[junit4] 2> 1477403 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/'
[junit4] 2> 1477402 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1477403 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/'
[junit4] 2> 1477403 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1477404 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 1477403 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/'
[junit4] 2> 1477404 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader path=/collections/testCloudExamplePrompt configName=testCloudExamplePrompt specified config exists in ZooKeeper
[junit4] 2> 1477404 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/'
[junit4] 2> 1477413 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1477416 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1477418 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1477418 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1477419 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1477419 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1477420 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/extraction/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/extraction/lib).
[junit4] 2> 1477420 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 1477420 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/clustering/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/clustering/lib).
[junit4] 2> 1477420 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 1477421 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/langid/lib).
[junit4] 2> 1477421 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1477421 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1477421 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 1477421 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1477421 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1477421 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/velocity/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/velocity/lib).
[junit4] 2> 1477421 WARN (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 1477422 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1477422 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/extraction/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/extraction/lib).
[junit4] 2> 1477422 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 1477422 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/clustering/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/clustering/lib).
[junit4] 2> 1477423 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 1477423 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/langid/lib).
[junit4] 2> 1477423 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 1477423 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/velocity/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/velocity/lib).
[junit4] 2> 1477423 WARN (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 1477424 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1477424 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1477424 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1477424 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1477424 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1477425 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/extraction/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/extraction/lib).
[junit4] 2> 1477425 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 1477425 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/clustering/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/clustering/lib).
[junit4] 2> 1477425 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 1477426 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/langid/lib).
[junit4] 2> 1477426 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 1477426 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/velocity/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/velocity/lib).
[junit4] 2> 1477426 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 1477429 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1477429 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1477429 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1477430 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1477430 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/extraction/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/extraction/lib).
[junit4] 2> 1477430 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 1477430 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/clustering/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/clustering/lib).
[junit4] 2> 1477431 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 1477431 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/langid/lib/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/langid/lib).
[junit4] 2> 1477431 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 1477432 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../contrib/velocity/lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/velocity/lib).
[junit4] 2> 1477432 WARN (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: ../../../../dist/ (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 1477433 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1477436 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1477437 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1477457 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1477461 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1477461 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1477463 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1477463 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1477465 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica2] Schema name=example-data-driven-schema
[junit4] 2> 1477466 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica1] Schema name=example-data-driven-schema
[junit4] 2> 1477472 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1477473 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1477479 INFO (qtp768125803-12363) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica2] Schema name=example-data-driven-schema
[junit4] 2> 1477488 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1477489 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1477495 INFO (qtp768125803-12365) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica1] Schema name=example-data-driven-schema
[junit4] 2> 1477581 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1477603 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1477604 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1477605 INFO (qtp768125803-12329) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1477617 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/testCloudExamplePrompt', cwd=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1477617 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard1_replica1' using configuration from collection testCloudExamplePrompt
[junit4] 2> 1477617 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1477617 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/], dataDir=[null]
[junit4] 2> 1477617 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d45a51d
[junit4] 2> 1477618 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard1_replica1] ] Added SolrEventListener for newSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 1477618 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard1_replica1] ] Added SolrEventListener for firstSearcher: [org.apache.solr.core.QuerySenderListener{queries=[]}]
[junit4] 2> 1477618 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/data
[junit4] 2> 1477618 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/data/index/
[junit4] 2> 1477619 WARN (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore [testCloudExamplePrompt_shard1_replica1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1477619 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/data/index
[junit4] 2> 1477620 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7ef89b2f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4fa28bef),segFN=segments_1,generation=1}
[junit4] 2> 1477620 INFO (qtp768125803-12327) [n:localhost:46702_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExampleP
[...truncated too long message...]
nt
[junit4] 2> 1498027 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.a.s.c.SolrCore [techproducts] Closing main searcher on request.
[junit4] 2> 1498041 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1498041 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts/data;done=false>>]
[junit4] 2> 1498042 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts/data
[junit4] 2> 1498042 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts/data/index;done=false>>]
[junit4] 2> 1498042 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts/data/index
[junit4] 2> 1498044 INFO (TEST-TestSolrCLIRunExample.testTechproductsExample-seed#[E20D15BD8E30B620]) [ x:techproducts] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@20717bcd{/solr,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCLIRunExample -Dtests.method=testTechproductsExample -Dtests.seed=E20D15BD8E30B620 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=et_EE -Dtests.timezone=US/Indiana-Starke -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 6.27s J0 | TestSolrCLIRunExample.testTechproductsExample <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected 32 docs in the techproducts example but found 0, output: Creating Solr home directory /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr
[junit4] > Starting up Solr on port 46362 using command:
[junit4] > /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/bin/solr start -p 46362 -s "temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr"
[junit4] > Setup new core instance directory:
[junit4] > /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001/tempDir-004/techproducts/solr/techproducts
[junit4] > Creating new core 'techproducts' using command:
[junit4] > http://localhost:46362/solr/admin/cores?action=CREATE&name=techproducts&instanceDir=techproducts
[junit4] > {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":180},
[junit4] > "core":"techproducts"}
[junit4] > Indexing tech product example docs from /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/example/exampledocs
[junit4] > Solr techproducts example launched successfully. Direct your Web browser to http://localhost:46362/solr to visit the Solr Admin UI
[junit4] > at __randomizedtesting.SeedInfo.seed([E20D15BD8E30B620:68DAC50594D28EBF]:0)
[junit4] > at org.apache.solr.util.TestSolrCLIRunExample.testExample(TestSolrCLIRunExample.java:357)
[junit4] > at org.apache.solr.util.TestSolrCLIRunExample.testTechproductsExample(TestSolrCLIRunExample.java:284)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1498048 INFO (SUITE-TestSolrCLIRunExample-seed#[E20D15BD8E30B620]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J0/temp/solr.util.TestSolrCLIRunExample_E20D15BD8E30B620-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {}, docValues:{}, sim=DefaultSimilarity, locale=et_EE, timezone=US/Indiana-Starke
[junit4] 2> NOTE: Linux 3.16.0-44-generic amd64/Oracle Corporation 1.9.0-ea (64-bit)/cpus=12,threads=1,free=211299888,total=530055168
[junit4] 2> NOTE: All tests run in this JVM: [TestFaceting, TestSweetSpotSimilarityFactory, TestIntervalFaceting, TestRebalanceLeaders, PKIAuthenticationIntegrationTest, SharedFSAutoReplicaFailoverUtilsTest, SchemaVersionSpecificBehaviorTest, DateFieldTest, TestRestManager, MultiTermTest, OverseerRolesTest, TestDynamicFieldCollectionResource, TestHighlightDedupGrouping, TestReplicationHandler, TestCustomSort, ClusterStateTest, QueryResultKeyTest, HighlighterConfigTest, CustomCollectionTest, DebugComponentTest, TestRemoteStreaming, TestPhraseSuggestions, TestRangeQuery, TestIBSimilarityFactory, DistributedQueryComponentCustomSortTest, EnumFieldTest, SaslZkACLProviderTest, TestCloudManagedSchema, TestCloudPivotFacet, MinimalSchemaTest, ConnectionManagerTest, UUIDFieldTest, TestMergePolicyConfig, DistributedSpellCheckComponentTest, DistributedDebugComponentTest, TestManagedStopFilterFactory, HdfsNNFailoverTest, TestRandomMergePolicy, MBeansHandlerTest, TestQuerySenderListener, ChangedSchemaMergeTest, TermsComponentTest, ParsingFieldUpdateProcessorsTest, LeaderElectionIntegrationTest, TestRandomDVFaceting, FastVectorHighlighterTest, PrimitiveFieldTypeTest, TestIndexingPerformance, BitVectorTest, TestRandomFaceting, TestBadConfig, TestHashQParserPlugin, TestShardHandlerFactory, ExitableDirectoryReaderTest, TestMiniSolrCloudCluster, TestSchemaNameResource, TestSchemaManager, TestCrossCoreJoin, TestDFRSimilarityFactory, ResponseHeaderTest, TestFunctionQuery, CursorPagingTest, DeleteShardTest, TestJsonFacets, TestConfig, TestSchemaSimilarityResource, AnalysisErrorHandlingTest, TestPostingsSolrHighlighter, DateRangeFieldTest, TestArbitraryIndexDir, DistributedExpandComponentTest, SimplePostToolTest, DistanceUnitsTest, TestReversedWildcardFilterFactory, TestLazyCores, TestPKIAuthenticationPlugin, JavabinLoaderTest, TestJmxIntegration, IndexSchemaTest, QueryElevationComponentTest, SolrRequestParserTest, TestCodecSupport, TestSha256AuthenticationProvider, SimpleCollectionCreateDeleteTest, TestJmxMonitoredMap, SuggesterFSTTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, OverseerTest, ZkCLITest, TestDistributedSearch, AliasIntegrationTest, CloudExitableDirectoryReaderTest, DeleteLastCustomShardedReplicaTest, DistribDocExpirationUpdateProcessorTest, LeaderFailoverAfterPartitionTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, SSLMigrationTest, SolrCloudExampleTest, TestRequestStatusCollectionAPI, TestConfigSetImmutable, TestConfigSetProperties, TestDynamicLoading, TestReqParamsAPI, TestRestoreCore, CoreAdminCreateDiscoverTest, CoreMergeIndexesAdminHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, DistributedFacetPivotLargeTest, DistributedFacetPivotLongTailTest, DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotWhiteBoxTest, DistributedSuggestComponentTest, FacetPivotSmallTest, SpatialHeatmapFacetsTest, TestTrackingShardHandlerFactory, TestBulkSchemaConcurrent, TestManagedSchema, AnalyticsMergeStrategyTest, CursorMarkTest, MergeStrategyTest, RankQueryTest, TestComplexPhraseQParserPlugin, TestFieldSortValues, TestInitQParser, TestNoOpRegenerator, TestSolr4Spatial2, TestStandardQParsers, TestTrieFacet, TestSortByMinMaxFunction, BJQParserTest, CloudMLTQParserTest, TestObjectReleaseTracker, TestRTimer, TestSolrCLIRunExample]
[junit4] Completed [526/531] on J0 in 22.01s, 3 tests, 1 failure <<< FAILURES!
[...truncated 29 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/build.xml:726: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/build.xml:670: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build.xml:230: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/common-build.xml:524: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/lucene/common-build.xml:1449: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/lucene/common-build.xml:1003: There were test failures: 531 suites, 2104 tests, 1 failure, 65 ignored (30 assumptions)
Total time: 43 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.3-Linux (64bit/jdk1.7.0_80) - Build # 62 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.3-Linux/62/
Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [TransactionLog]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [TransactionLog]
at __randomizedtesting.SeedInfo.seed([786C814812B9F719]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:236)
at sun.reflect.GeneratedMethodAccessor37.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11142 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/init-core-data-001
[junit4] 2> 1146721 INFO (SUITE-HttpPartitionTest-seed#[786C814812B9F719]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /za_pv/
[junit4] 2> 1146723 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1146724 INFO (Thread-3716) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1146724 INFO (Thread-3716) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1146824 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ZkTestServer start zk server on port:41586
[junit4] 2> 1146824 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1146824 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1146825 INFO (zkCallback-1116-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ae21f13 name:ZooKeeperConnection Watcher:127.0.0.1:41586 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1146826 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1146826 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1146826 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1146827 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1146827 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1146828 INFO (zkCallback-1117-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@45b212c0 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1146828 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1146829 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1146829 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 1146830 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 1146830 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 1146830 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 1146831 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1146831 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1146832 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1146832 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1146833 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1146833 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1146834 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1146834 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1146834 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1146834 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1146835 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1146835 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1146836 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1146836 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1146836 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1146836 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1146837 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1146837 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1146837 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1146838 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1146838 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1146838 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1146878 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1
[junit4] 2> 1146880 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1146880 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11096e65{/za_pv,null,AVAILABLE}
[junit4] 2> 1146881 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.ServerConnector Started ServerConnector@478f807e{HTTP/1.1}{127.0.0.1:47567}
[junit4] 2> 1146881 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.Server Started @1148637ms
[junit4] 2> 1146881 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/za_pv, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores, hostPort=58962, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/tempDir-001/control/data}
[junit4] 2> 1146882 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@36d98810
[junit4] 2> 1146882 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/'
[junit4] 2> 1146890 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1146891 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1146892 INFO (zkCallback-1118-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74a6edf3 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1146892 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1146892 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1146892 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1146892 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/solr.xml
[junit4] 2> 1146899 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores
[junit4] 2> 1146900 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoreContainer New CoreContainer 2138915090
[junit4] 2> 1146900 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/]
[junit4] 2> 1146900 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/lib
[junit4] 2> 1146900 WARN (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/lib).
[junit4] 2> 1146904 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1146905 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1146905 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1146905 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1146905 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41586/solr
[junit4] 2> 1146905 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1146905 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1146906 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1146906 INFO (zkCallback-1120-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@45fa9b8d name:ZooKeeperConnection Watcher:127.0.0.1:41586 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1146907 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1146907 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1146908 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1146908 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25c6626 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1146908 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1146909 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1146910 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1146911 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1146911 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1146912 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1146913 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1146914 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1146914 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1146914 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1146915 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58962_za_pv
[junit4] 2> 1146915 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58962_za_pv
[junit4] 2> 1146916 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1146916 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1146916 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1146917 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94342875208810500-127.0.0.1:58962_za_pv-n_0000000000
[junit4] 2> 1146917 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58962_za_pv
[junit4] 2> 1146917 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1146917 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer Overseer (id=94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) starting
[junit4] 2> 1146918 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1146921 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1146921 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1146921 INFO (OverseerCollectionProcessor-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
[junit4] 2> 1146921 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1146929 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1146930 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1146930 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores
[junit4] 2> 1146931 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.SolrCore Created CoreDescriptor: {schema=schema.xml, transient=false, coreNodeName=, collection=control_collection, loadOnStartup=true, shard=, config=solrconfig.xml, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/, name=collection1, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1, dataDir=data/, configSetProperties=configsetprops.json}
[junit4] 2> 1146931 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/
[junit4] 2> 1146931 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1146931 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1146931 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1146932 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:58962_za_pv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1146931 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1146932 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1146932 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1146933 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:58962_za_pv",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1:58962/za_pv",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "collection":"control_collection"} current state version: 0
[junit4] 2> 1146933 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "node_name":"127.0.0.1:58962_za_pv",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1:58962/za_pv",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "collection":"control_collection"}
[junit4] 2> 1146933 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 1146933 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1146934 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1146934 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
[junit4] 2> 1147932 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 1147932 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 1147933 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1147933 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
[junit4] 2> 1147933 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1147933 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/'
[junit4] 2> 1147941 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1147944 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1147951 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1147966 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1147966 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1147970 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1148099 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1148104 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1148106 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1148111 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1148113 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1148115 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1148115 WARN (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1148115 WARN (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1148115 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1148115 WARN (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1148115 WARN (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1148116 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1148116 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1148116 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1148116 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/], dataDir=[null]
[junit4] 2> 1148116 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d4e6e5f
[junit4] 2> 1148117 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/data
[junit4] 2> 1148117 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/data/index/
[junit4] 2> 1148117 WARN (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1148117 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/data/index
[junit4] 2> 1148117 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=37.7880859375, floorSegmentMB=0.3818359375, forceMergeDeletesPctAllowed=28.944924245437324, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1148118 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5e8f0cce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@191d8e2),segFN=segments_1,generation=1}
[junit4] 2> 1148118 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1148119 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/data/
[junit4] 2> 1148119 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1148121 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1148121 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1148121 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1148121 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1148121 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1148122 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1148122 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1148122 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1148122 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1148124 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1148126 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1148127 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1148128 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1148131 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/properties,/admin/file,/admin/plugins,/config,/update/json/docs,/update/json,/admin/system,/admin/luke,/schema,/admin/logging,/admin/ping,/admin/threads,/admin/segments,/replication,/admin/mbeans,/update,standard,/get,/update/csv
[junit4] 2> 1148132 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1148133 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1148133 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1148134 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1148134 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1148134 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=801157665, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1148135 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5e8f0cce lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@191d8e2),segFN=segments_1,generation=1}
[junit4] 2> 1148135 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1148135 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@782a7868[collection1] main
[junit4] 2> 1148135 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
[junit4] 2> 1148136 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1148136 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1148136 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1148136 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1148136 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1148137 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1148137 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1148137 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1148137 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1148138 INFO (searcherExecutor-4292-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@782a7868[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1148138 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1148138 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1148138 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1148138 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1509486004822605824
[junit4] 2> 1148140 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1509486004822605824
[junit4] 2> 1148140 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1148141 INFO (coreLoadExecutor-4291-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1148141 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:58962/za_pv collection:control_collection shard:shard1
[junit4] 2> 1148141 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1148143 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/94342875208810500-core_node1-n_0000000000
[junit4] 2> 1148143 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 1148144 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1148144 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1148144 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1148144 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 1148144 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58962/za_pv/collection1/
[junit4] 2> 1148145 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1148145 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58962/za_pv/collection1/ has no replicas
[junit4] 2> 1148145 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:58962/za_pv/collection1/ shard1
[junit4] 2> 1148145 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1148146 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1148147 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:58962/za_pv",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1148248 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148249 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
[junit4] 2> 1148297 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58962/za_pv/collection1/ and leader is http://127.0.0.1:58962/za_pv/collection1/
[junit4] 2> 1148297 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58962/za_pv
[junit4] 2> 1148297 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1148297 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 1148297 INFO (coreZkRegister-4285-thread-1-processing-n:127.0.0.1:58962_za_pv x:collection1 s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:58962_za_pv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1148298 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1148298 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:58962_za_pv",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1:58962/za_pv",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"control_collection"} current state version: 2
[junit4] 2> 1148298 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "node_name":"127.0.0.1:58962_za_pv",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1:58962/za_pv",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"active",
[junit4] 2> "collection":"control_collection"}
[junit4] 2> 1148400 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148401 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
[junit4] 2> 1148434 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148435 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148436 INFO (zkCallback-1123-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f436db0 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148436 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148436 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148437 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1148438 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1148438 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 1148438 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148438 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148439 INFO (zkCallback-1124-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ec3a6b8 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148439 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148440 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148441 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1148441 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 3
[junit4] 2> 1148441 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 1148483 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1
[junit4] 2> 1148484 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001
[junit4] 2> 1148485 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1148485 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2935af75{/za_pv,null,AVAILABLE}
[junit4] 2> 1148486 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.ServerConnector Started ServerConnector@76845dce{HTTP/1.1}{127.0.0.1:38597}
[junit4] 2> 1148486 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.e.j.s.Server Started @1150242ms
[junit4] 2> 1148486 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostContext=/za_pv, hostPort=43982, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/tempDir-001/jetty1}
[junit4] 2> 1148486 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@36d98810
[junit4] 2> 1148486 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/'
[junit4] 2> 1148495 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148495 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148496 INFO (zkCallback-1125-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@32b5d542 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148496 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148497 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148497 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1148497 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/solr.xml
[junit4] 2> 1148515 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores
[junit4] 2> 1148515 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoreContainer New CoreContainer 2072819752
[junit4] 2> 1148515 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/]
[junit4] 2> 1148515 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/lib
[junit4] 2> 1148515 WARN (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/lib).
[junit4] 2> 1148520 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1148520 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1148521 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1148521 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1148521 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41586/solr
[junit4] 2> 1148521 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1148521 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148521 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148522 INFO (zkCallback-1127-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@27694279 name:ZooKeeperConnection Watcher:127.0.0.1:41586 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148523 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148523 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148524 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148524 INFO (zkCallback-1128-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54f1af68 name:ZooKeeperConnection Watcher:127.0.0.1:41586/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148524 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148526 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1148543 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
[junit4] 2> 1148544 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148544 INFO (zkCallback-1128-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148544 INFO (zkCallback-1123-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148545 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
[junit4] 2> 1148545 INFO (zkCallback-1128-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
[junit4] 2> 1148545 INFO (zkCallback-1123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
[junit4] 2> 1149528 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43982_za_pv
[junit4] 2> 1149528 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43982_za_pv
[junit4] 2> 1149529 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1149530 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94342875208810505-127.0.0.1:43982_za_pv-n_0000000001
[junit4] 2> 1149530 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94342875208810500-127.0.0.1:58962_za_pv-n_0000000000 to know if I could be the leader
[junit4] 2> 1149537 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1149537 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1149537 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores
[junit4] 2> 1149538 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.SolrCore Created CoreDescriptor: {dataDir=data/, collection=collection1, shard=, instanceDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1, schema=schema.xml, coreNodeName=, name=collection1, configSetProperties=configsetprops.json, config=solrconfig.xml, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/, loadOnStartup=true, transient=false}
[junit4] 2> 1149538 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/
[junit4] 2> 1149538 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1149539 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1149539 INFO (TEST-HttpPartitionTest.test-seed#[786C814812B9F719]) [n:127.0.0.1:43982_za_pv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1149539 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1149539 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1149539 INFO (zkCallback-1121-thread-1-processing-n:127.0.0.1:58962_za_pv) [n:127.0.0.1:58962_za_pv ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1149539 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1
[junit4] 2> 1149540 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1
[junit4] 2> 1149540 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:43982_za_pv",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:43982/za_pv",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 1149540 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1149540 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "node_name":"127.0.0.1:43982_za_pv",
[junit4] 2> "numShards":"2",
[junit4] 2> "base_url":"http://127.0.0.1:43982/za_pv",
[junit4] 2> "roles":null,
[junit4] 2> "operation":"state",
[junit4] 2> "core":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "state":"down",
[junit4] 2> "collection":"collection1"}
[junit4] 2> 1149540 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
[junit4] 2> 1149540 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 0
[junit4] 2> 1149540 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 1149540 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1149641 INFO (OverseerStateUpdate-94342875208810500-127.0.0.1:58962_za_pv-n_0000000000) [n:127.0.0.1:58962_za_pv ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1149642 INFO (zkCallback-1128-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2)
[junit4] 2> 1149642 INFO (zkCallback-1128-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv ] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 1
[junit4] 2> 1150540 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 1150541 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1
[junit4] 2> 1150541 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1150541 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
[junit4] 2> 1150541 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1150541 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/'
[junit4] 2> 1150549 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1150553 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1150559 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1150574 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1150575 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1150579 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1150708 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1150712 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1150713 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1150717 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1150719 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1150720 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1150720 WARN (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1150720 WARN (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1150721 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1150721 WARN (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1150721 WARN (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1150721 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2
[junit4] 2> 1150722 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1150722 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1150722 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/], dataDir=[null]
[junit4] 2> 1150722 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@d4e6e5f
[junit4] 2> 1150723 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/data
[junit4] 2> 1150723 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 1150723 WARN (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1150723 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 1150723 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=37.7880859375, floorSegmentMB=0.3818359375, forceMergeDeletesPctAllowed=28.944924245437324, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1150724 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@28e0c744 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3d3a5653),segFN=segments_1,generation=1}
[junit4] 2> 1150724 INFO (coreLoadExecutor-4302-thread-1-processing-n:127.0.0.1:43982_za_pv) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1150724 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:43982_za_pv c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.HttpPartitionTest_786C814812B9F719-001/shard-1-001/cores/collection1/data/
[junit4
[...truncated too long message...]
vaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:179)
[junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:135)
[junit4] 2> at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:249)
[junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:121)
[junit4] 2> at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:214)
[junit4] 2> at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:134)
[junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:186)
[junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:111)
[junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:58)
[junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:98)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:143)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2068)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:669)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:462)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:210)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:179)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:499)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> Aug 14, 2015 1:16:04 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {_version_=FSTOrd50, a_t=PostingsFormat(name=Direct), text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), timestamp=PostingsFormat(name=Direct), intDefault=FSTOrd50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l=PostingsFormat(name=Direct), range_facet_i_dv=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=Direct), range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{timestamp=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Direct), range_facet_l_dv=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=sr_ME, timezone=Asia/Dili
[junit4] 2> NOTE: Linux 3.16.0-44-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=12,threads=1,free=304090496,total=528482304
[junit4] 2> NOTE: All tests run in this JVM: [TestQueryTypes, SampleTest, BufferStoreTest, ResponseLogComponentTest, RuleEngineTest, TestRestoreCore, TestReplicaProperties, RemoteQueryErrorTest, SortByFunctionTest, TestFreeTextSuggestions, OverseerTest, FullSolrCloudDistribCmdsTest, TestRandomRequestDistribution, TestUtils, SpatialHeatmapFacetsTest, PrimUtilsTest, HLLSerializationTest, ExplicitHLLTest, SimpleCollectionCreateDeleteTest, XmlUpdateRequestHandlerTest, FacetPivotSmallTest, TestLuceneMatchVersion, TestRequestStatusCollectionAPI, TestSort, AutoCommitTest, DirectSolrSpellCheckerTest, BigEndianAscendingWordSerializerTest, TestInfoStreamLogging, TestConfigSets, DocValuesTest, TestAddFieldRealTimeGet, TestTrackingShardHandlerFactory, TestPartialUpdateDeduplication, DistributedIntervalFacetingTest, SimpleFacetsTest, TestSolrConfigHandlerConcurrent, TestRecovery, TestDistribDocBasedVersion, TestAuthorizationFramework, RulesTest, TestExceedMaxTermLength, TestComplexPhraseQParserPlugin, TestZkChroot, ChangedSchemaMergeTest, TestScoreJoinQPScore, DistributedQueryElevationComponentTest, TestFileDictionaryLookup, CollectionTooManyReplicasTest, TestClusterStateMutator, TestBulkSchemaConcurrent, TestXIncludeConfig, HardAutoCommitTest, SparseHLLTest, TestCollationFieldDocValues, TestSystemIdResolver, TestCSVResponseWriter, StatsComponentTest, TestBulkSchemaAPI, RegexBoostProcessorTest, DirectUpdateHandlerTest, TestFastOutputStream, TestSolrXml, DistributedFacetPivotLargeTest, TestExpandComponent, TestManagedStopFilterFactory, TestGroupingSearch, DirectUpdateHandlerOptimizeTest, CircularListTest, TestPerFieldSimilarity, DistributedQueryComponentCustomSortTest, HdfsBasicDistributedZk2Test, TestBM25SimilarityFactory, DistribCursorPagingTest, TestCloudPivotFacet, TestQueryUtils, TestSchemaNameResource, DataDrivenBlockJoinTest, DocExpirationUpdateProcessorFactoryTest, OverseerCollectionProcessorTest, QueryResultKeyTest, QueryEqualityTest, HdfsBasicDistributedZkTest, CachingDirectoryFactoryTest, BinaryUpdateRequestHandlerTest, TestReloadAndDeleteDocs, LeaderElectionTest, DistributedSpellCheckComponentTest, TestStressReorder, DistributedTermsComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestLazyCores, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, TestFoldingMultitermQuery, SuggesterTSTTest, TestTrie, SpatialFilterTest, TestCSVLoader, PolyFieldTest, SolrCoreCheckLockOnStartupTest, StatelessScriptUpdateProcessorFactoryTest, SolrInfoMBeanTest, DebugComponentTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, TestOmitPositions, RequestHandlersTest, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, RequiredFieldsTest, LoggingHandlerTest, IndexSchemaTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, PingRequestHandlerTest, TestBinaryResponseWriter, TestQuerySenderListener, TestSolrIndexConfig, BadComponentTest, TestStressRecovery, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, AssignTest, AsyncMigrateRouteKeyTest, CustomCollectionTest, HttpPartitionTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionTest -Dtests.seed=786C814812B9F719 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_ME -Dtests.timezone=Asia/Dili -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | HttpPartitionTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [TransactionLog]
[junit4] > at __randomizedtesting.SeedInfo.seed([786C814812B9F719]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:236)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [412/531] on J2 in 132.78s, 1 test, 1 failure <<< FAILURES!
[...truncated 388 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/build.xml:726: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/build.xml:670: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/build.xml:230: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/solr/common-build.xml:524: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/lucene/common-build.xml:1449: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.3-Linux/lucene/common-build.xml:1003: There were test failures: 531 suites, 2104 tests, 1 suite-level error, 65 ignored (30 assumptions)
Total time: 48 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any