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/04 22:10:52 UTC
[JENKINS-EA] Lucene-Solr-trunk-Linux (32bit/jdk1.9.0-ea-b60) -
Build # 13732 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/13732/
Java: 32bit/jdk1.9.0-ea-b60 -client -XX:+UseConcMarkSweepGC -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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-003/techproducts/solr Starting up Solr on port 51809 using command: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bin/solr start -p 51809 -s "temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-003/techproducts/solr" Setup new core instance directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-003/techproducts/solr/techproducts Creating new core 'techproducts' using command: http://localhost:51809/solr/admin/cores?action=CREATE&name=techproducts&instanceDir=techproducts { "responseHeader":{ "status":0, "QTime":668}, "core":"techproducts"} example/exampledocs/post.jar not found, skipping indexing step for the techproducts example Solr techproducts example launched successfully. Direct your Web browser to http://localhost:51809/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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-003/techproducts/solr
Starting up Solr on port 51809 using command:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/bin/solr start -p 51809 -s "temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-003/techproducts/solr"
Setup new core instance directory:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-003/techproducts/solr/techproducts
Creating new core 'techproducts' using command:
http://localhost:51809/solr/admin/cores?action=CREATE&name=techproducts&instanceDir=techproducts
{
"responseHeader":{
"status":0,
"QTime":668},
"core":"techproducts"}
example/exampledocs/post.jar not found, skipping indexing step for the techproducts example
Solr techproducts example launched successfully. Direct your Web browser to http://localhost:51809/solr to visit the Solr Admin UI
at __randomizedtesting.SeedInfo.seed([54607B3532BDDC2:8F91D70B49C9E55D]: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:368)
at org.apache.solr.util.TestSolrCLIRunExample.testTechproductsExample(TestSolrCLIRunExample.java:305)
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 9599 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/init-core-data-001
[junit4] 2> 2058 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 2108 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2111 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2111 INFO (Thread-8) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2212 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.ZkTestServer start zk server on port:37522
[junit4] 2> 2226 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2263 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2287 INFO (zkCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@141ae0b name:ZooKeeperConnection Watcher:127.0.0.1:37522 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2288 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2289 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2290 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 2335 INFO (jetty-launcher-4-thread-1) [ ] o.e.j.u.log Logging initialized @3779ms
[junit4] 2> 2479 INFO (jetty-launcher-4-thread-1) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 2530 INFO (jetty-launcher-4-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7905ae{/solr,null,AVAILABLE}
[junit4] 2> 2536 INFO (jetty-launcher-4-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@882656{HTTP/1.1}{127.0.0.1:40832}
[junit4] 2> 2539 INFO (jetty-launcher-4-thread-1) [ ] o.e.j.s.Server Started @3984ms
[junit4] 2> 2540 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40832}
[junit4] 2> 2544 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1fe3ee3
[junit4] 2> 2544 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/'
[junit4] 2> 2562 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2562 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2564 INFO (zkCallback-6-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7742ef name:ZooKeeperConnection Watcher:127.0.0.1:37522/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2564 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2564 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2565 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2582 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002
[junit4] 2> 2583 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 28226342
[junit4] 2> 2583 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/]
[junit4] 2> 2583 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/lib
[junit4] 2> 2583 WARN (jetty-launcher-4-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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/lib).
[junit4] 2> 2595 INFO (jetty-launcher-4-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> 2596 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 2596 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2597 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2597 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37522/solr
[junit4] 2> 2606 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 2606 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2607 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2608 INFO (zkCallback-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2790c8 name:ZooKeeperConnection Watcher:127.0.0.1:37522 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2608 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2609 INFO (jetty-launcher-4-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2613 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2614 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d4a939 name:ZooKeeperConnection Watcher:127.0.0.1:37522/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2614 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2620 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 2624 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 2626 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 2629 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 2632 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 2642 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2645 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2647 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2649 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2650 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:40832_solr
[junit4] 2> 2653 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/localhost:40832_solr
[junit4] 2> 2657 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 2659 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 2660 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2665 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94287765333671939-localhost:40832_solr-n_0000000000
[junit4] 2> 2669 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:40832_solr
[junit4] 2> 2672 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 2674 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.Overseer Overseer (id=94287765333671939-localhost:40832_solr-n_0000000000) starting
[junit4] 2> 2683 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 2705 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 2763 INFO (OverseerCollectionProcessor-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
[junit4] 2> 2763 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2767 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 2775 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2776 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2777 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002
[junit4] 2> 2779 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.c.CoresLocator Found 0 core definitions
[junit4] 2> 2781 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 2781 INFO (jetty-launcher-4-thread-1) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2781 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2783 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2785 INFO (zkCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@915fc5 name:ZooKeeperConnection Watcher:127.0.0.1:37522 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2786 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2787 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3124 INFO (qtp5990088-43) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=13
[junit4] 2> 3187 INFO (qtp5990088-44) [n:localhost:40832_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json
[junit4] 2> 3192 INFO (qtp5990088-44) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=23
[junit4] 2> 3195 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3195 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3198 INFO (zkCallback-13-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1798de6 name:ZooKeeperConnection Watcher:127.0.0.1:37522/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3198 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3198 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3199 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3205 INFO (qtp5990088-43) [n:localhost:40832_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json
[junit4] 2> 3206 INFO (qtp5990088-43) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 3214 INFO (qtp5990088-45) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=4
[junit4] 2> 3219 INFO (qtp5990088-43) [n:localhost:40832_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json
[junit4] 2> 3221 INFO (qtp5990088-43) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=2
[junit4] 2> 3222 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 3222 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3224 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d700b name:ZooKeeperConnection Watcher:127.0.0.1:37522/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3224 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 3225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 3232 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/protwords.txt
[junit4] 2> 3235 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/solrconfig.xml
[junit4] 2> 3239 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/stopwords.txt
[junit4] 2> 3247 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ar.txt
[junit4] 2> 3251 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_el.txt
[junit4] 2> 3256 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ja.txt
[junit4] 2> 3259 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_gl.txt
[junit4] 2> 3262 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_fr.txt
[junit4] 2> 3264 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fr.txt
[junit4] 2> 3267 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_nl.txt
[junit4] 2> 3270 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_cz.txt
[junit4] 2> 3273 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ca.txt
[junit4] 2> 3277 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_de.txt
[junit4] 2> 3280 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ga.txt
[junit4] 2> 3282 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_da.txt
[junit4] 2> 3285 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_no.txt
[junit4] 2> 3289 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_es.txt
[junit4] 2> 3292 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_sv.txt
[junit4] 2> 3296 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fi.txt
[junit4] 2> 3299 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hu.txt
[junit4] 2> 3302 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_bg.txt
[junit4] 2> 3305 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fa.txt
[junit4] 2> 3307 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_it.txt
[junit4] 2> 3310 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_eu.txt
[junit4] 2> 3313 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ro.txt
[junit4] 2> 3315 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ga.txt
[junit4] 2> 3317 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stemdict_nl.txt
[junit4] 2> 3323 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hy.txt
[junit4] 2> 3325 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ca.txt
[junit4] 2> 3327 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stoptags_ja.txt
[junit4] 2> 3330 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_lv.txt
[junit4] 2> 3333 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/userdict_ja.txt
[junit4] 2> 3336 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_tr.txt
[junit4] 2> 3340 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_it.txt
[junit4] 2> 3342 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ru.txt
[junit4] 2> 3345 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_th.txt
[junit4] 2> 3349 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/hyphenations_ga.txt
[junit4] 2> 3351 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_en.txt
[junit4] 2> 3353 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hi.txt
[junit4] 2> 3356 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_pt.txt
[junit4] 2> 3372 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_id.txt
[junit4] 2> 3375 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/synonyms.txt
[junit4] 2> 3377 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/elevate.xml
[junit4] 2> 3380 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/currency.xml
[junit4] 2> 3382 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/params.json
[junit4] 2> 3384 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 3392 INFO (qtp5990088-45) [n:localhost:40832_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json
[junit4] 2> 3392 INFO (qtp5990088-45) [n:localhost:40832_solr ] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 3397 INFO (qtp5990088-44) [n:localhost:40832_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> 3404 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/collection-queue-work state SyncConnected
[junit4] 2> 3405 INFO (OverseerCollectionProcessor-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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> 3407 WARN (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_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> 3412 WARN (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_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> 3413 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor creating collections conf node /collections/testCloudExamplePrompt
[junit4] 2> 3414 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testCloudExamplePrompt
[junit4] 2> 3416 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3417 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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> 3420 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCloudExamplePrompt
[junit4] 2> 3431 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCloudExamplePrompt/state.json
[junit4] 2> 3435 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_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> 3438 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
[junit4] 2> 3442 INFO (zkCallback-15-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> 3443 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
[junit4] 2> 3518 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor Creating SolrCores for new collection testCloudExamplePrompt, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 3518 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard1_replica2 as part of shard shard1 of collection testCloudExamplePrompt on localhost:40832_solr
[junit4] 2> 3523 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard1_replica1 as part of shard shard1 of collection testCloudExamplePrompt on localhost:40832_solr
[junit4] 2> 3525 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard2_replica2 as part of shard shard2 of collection testCloudExamplePrompt on localhost:40832_solr
[junit4] 2> 3527 INFO (OverseerThreadFactory-31-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.OverseerCollectionProcessor Creating core testCloudExamplePrompt_shard2_replica1 as part of shard shard2 of collection testCloudExamplePrompt on localhost:40832_solr
[junit4] 2> 3536 INFO (qtp5990088-43) [n:localhost:40832_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> 3536 INFO (qtp5990088-81) [n:localhost:40832_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> 3536 INFO (qtp5990088-80) [n:localhost:40832_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> 3536 INFO (qtp5990088-45) [n:localhost:40832_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> 3540 INFO (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 3541 INFO (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 3540 INFO (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 3541 INFO (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 3542 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3543 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3543 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3544 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 3545 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3545 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 3546 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader addZkWatch testCloudExamplePrompt
[junit4] 2> 3546 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 3545 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 3546 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/testCloudExamplePrompt/state.json from ZooKeeper...
[junit4] 2> 3546 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.Overseer processMessage: queueSize: 4, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:40832/solr",
[junit4] 2> "node_name":"localhost:40832_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> 3546 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Registering watch for external collection testCloudExamplePrompt
[junit4] 2> 3551 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Updating data for testCloudExamplePrompt to ver 0
[junit4] 2> 3551 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3551 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3551 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3551 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 3551 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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:40832/solr",
[junit4] 2> "node_name":"localhost:40832_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3557 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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:40832/solr",
[junit4] 2> "node_name":"localhost:40832_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> 3562 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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:40832/solr",
[junit4] 2> "node_name":"localhost:40832_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3567 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3568 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:40832/solr",
[junit4] 2> "node_name":"localhost:40832_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> 3569 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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:40832/solr",
[junit4] 2> "node_name":"localhost:40832_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3572 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:40832/solr",
[junit4] 2> "node_name":"localhost:40832_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> 3572 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_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:40832/solr",
[junit4] 2> "node_name":"localhost:40832_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 3575 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 3576 INFO (OverseerStateUpdate-94287765333671939-localhost:40832_solr-n_0000000000) [n:localhost:40832_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCloudExamplePrompt/state.json version: 0
[junit4] 2> 3577 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_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> 3578 INFO (zkCallback-9-thread-1-processing-n:localhost:40832_solr) [n:localhost:40832_solr ] o.a.s.c.c.ZkStateReader Updating data for testCloudExamplePrompt to ver 1
[junit4] 2> 4552 INFO (qtp5990088-45) [n:localhost:40832_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> 4552 INFO (qtp5990088-43) [n:localhost:40832_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> 4552 INFO (qtp5990088-81) [n:localhost:40832_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> 4552 INFO (qtp5990088-80) [n:localhost:40832_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> 4553 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 4553 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 4552 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 4554 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 4554 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 4553 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 4554 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 4554 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 4554 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 4555 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 4555 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 4556 INFO (qtp5990088-81) [n:localhost:40832_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> 4556 INFO (qtp5990088-45) [n:localhost:40832_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> 4556 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Load collection config from:/collections/testCloudExamplePrompt
[junit4] 2> 4559 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/'
[junit4] 2> 4559 INFO (qtp5990088-43) [n:localhost:40832_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> 4559 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/'
[junit4] 2> 4560 INFO (qtp5990088-80) [n:localhost:40832_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> 4560 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/'
[junit4] 2> 4561 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/'
[junit4] 2> 4591 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 4607 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 4665 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 4666 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 4676 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4678 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4679 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 4680 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 4681 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/extraction/lib).
[junit4] 2> 4682 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 4684 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/clustering/lib).
[junit4] 2> 4684 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 4685 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4685 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4686 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/langid/lib).
[junit4] 2> 4692 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 4692 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 4693 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../contrib/velocity/lib).
[junit4] 2> 4694 WARN (qtp5990088-80) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/../../../../dist).
[junit4] 2> 4694 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 4695 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/extraction/lib).
[junit4] 2> 4695 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 4696 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/clustering/lib).
[junit4] 2> 4728 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 4728 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/langid/lib).
[junit4] 2> 4729 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 4730 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../contrib/velocity/lib).
[junit4] 2> 4730 WARN (qtp5990088-45) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica1/../../../../dist).
[junit4] 2> 4732 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4733 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4733 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 4734 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 4734 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 4735 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 4735 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 4735 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/extraction/lib).
[junit4] 2> 4736 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 4736 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 4737 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/clustering/lib).
[junit4] 2> 4751 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/extraction/lib).
[junit4] 2> 4770 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 4771 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 4771 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/langid/lib).
[junit4] 2> 4771 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/clustering/lib).
[junit4] 2> 4772 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 4772 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 4773 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../contrib/velocity/lib).
[junit4] 2> 4773 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/langid/lib).
[junit4] 2> 4773 WARN (qtp5990088-81) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/../../../../dist).
[junit4] 2> 4774 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 4775 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../contrib/velocity/lib).
[junit4] 2> 4776 WARN (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/../../../../dist).
[junit4] 2> 4840 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4840 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4840 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 4877 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 5040 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 5041 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 5044 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 5044 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 5054 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 5055 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 5055 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 5055 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 5082 INFO (qtp5990088-80) [n:localhost:40832_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> 5095 INFO (qtp5990088-45) [n:localhost:40832_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> 5106 INFO (qtp5990088-43) [n:localhost:40832_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> 5107 INFO (qtp5990088-81) [n:localhost:40832_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> 6542 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 6542 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 6565 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 6575 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 7038 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7039 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7043 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7045 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7053 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7055 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7053 INFO (qtp5990088-45) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7056 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 7154 INFO (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 7155 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] 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-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 7155 INFO (qtp5990088-43) [n:localhost:40832_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> 7155 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard1_replica2' using configuration from collection testCloudExamplePrompt
[junit4] 2> 7156 INFO (qtp5990088-43) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 r:core_node2 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 7156 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 r:core_node4 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 7156 INFO (qtp5990088-43) [n:localhost:40832_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica1/], dataDir=[null]
[junit4] 2> 7156 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 r:core_node4 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard1_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard1_replica2/], dataDir=[null]
[junit4] 2> 7164 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] 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-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 7164 INFO (qtp5990088-43) [n:localhost:40832_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@1a15912
[junit4] 2> 7164 INFO (qtp5990088-81) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard1 r:core_node4 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a15912
[junit4] 2> 7165 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.CoreContainer Creating SolrCore 'testCloudExamplePrompt_shard2_replica2' using configuration from collection testCloudExamplePrompt
[junit4] 2> 7175 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 r:core_node3 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 7175 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 r:core_node3 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrCore [[testCloudExamplePrompt_shard2_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-002/testCloudExamplePrompt_shard2_replica2/], dataDir=[null]
[junit4] 2> 7175 INFO (qtp5990088-80) [n:localhost:40832_solr c:testCloudExamplePrompt s:shard2 r:core_node3 x:
[...truncated too long message...]
32BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/conf
[junit4] 2> 30909 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 30909 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 30910 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 30910 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent No queryConverter defined, using default converter
[junit4] 2> 30910 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/conf/elevate.xml
[junit4] 2> 30915 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 30915 INFO (searcherExecutor-88-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore QuerySenderListener sending requests to Searcher@bde481[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 30916 INFO (searcherExecutor-88-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore QuerySenderListener done.
[junit4] 2> 30916 INFO (searcherExecutor-88-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 30916 INFO (searcherExecutor-88-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 30930 INFO (searcherExecutor-88-thread-1-processing-x:gettingstarted) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Registered new searcher Searcher@bde481[gettingstarted] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 30931 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 30931 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 30931 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 30931 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1508604275511525376
[junit4] 2> 30933 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1508604275511525376
[junit4] 2> 30933 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.c.CoreContainer registering core: gettingstarted
[junit4] 2> 30934 INFO (qtp5107429-219) [ x:gettingstarted] o.a.s.s.SolrDispatchFilter [admin] webapp=null path=/admin/cores params={name=gettingstarted&action=CREATE&instanceDir=gettingstarted&wt=json} status=0 QTime=555
[junit4] 2> 30936 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1a0881b{HTTP/1.1}{127.0.0.1:38136}
[junit4] 2> 30936 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=32132565
[junit4] 2> 30937 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] CLOSING SolrCore org.apache.solr.core.SolrCore@1950176
[junit4] 2> 30938 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=0,autocommit maxTime=15000ms,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 30938 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 30938 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 30938 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 30939 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.SolrCore [gettingstarted] Closing main searcher on request.
[junit4] 2> 30985 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 30986 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/data [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/data;done=false>>]
[junit4] 2> 30986 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/data
[junit4] 2> 30986 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/data/index [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/data/index;done=false>>]
[junit4] 2> 30987 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001/tempDir-004/schemaless/solr/gettingstarted/data/index
[junit4] 2> 30988 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ x:gettingstarted] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@11eb7fa{/solr,null,UNAVAILABLE}
[junit4] 2> 30989 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ ] o.a.s.u.TestSolrCLIRunExample Stopped standalone Solr instance running on port 38136
[junit4] 2> 30989 INFO (TEST-TestSolrCLIRunExample.testSchemalessExample-seed#[54607B3532BDDC2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemalessExample
[junit4] 2> 30991 INFO (SUITE-TestSolrCLIRunExample-seed#[54607B3532BDDC2]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.util.TestSolrCLIRunExample_54607B3532BDDC2-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {}, docValues:{}, sim=DefaultSimilarity, locale=sr_RS_#Latn, timezone=Africa/Maseru
[junit4] 2> NOTE: Linux 3.16.0-44-generic i386/Oracle Corporation 1.9.0-ea (32-bit)/cpus=12,threads=1,free=8770688,total=64229376
[junit4] 2> NOTE: All tests run in this JVM: [TestEmbeddedSolrServerConstructors, SynonymTokenizerTest, TestSolrCLIRunExample]
[junit4] Completed [7/531] on J2 in 28.97s, 3 tests, 1 failure <<< FAILURES!
[...truncated 1631 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:716: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:660: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:230: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:514: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1429: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:984: There were test failures: 531 suites, 2104 tests, 1 failure, 65 ignored (30 assumptions)
Total time: 52 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_51) - Build #
13733 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/13733/
Java: 32bit/jdk1.8.0_51 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest
Error Message:
Captured an uncaught exception in thread: Thread[id=3219, name=RecoveryThread-source_collection_shard1_replica1, state=RUNNABLE, group=TGRP-CdcrReplicationHandlerTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3219, name=RecoveryThread-source_collection_shard1_replica1, state=RUNNABLE, group=TGRP-CdcrReplicationHandlerTest]
Caused by: org.apache.solr.common.cloud.ZooKeeperException:
at __randomizedtesting.SeedInfo.seed([3C89B73603097DF3]:0)
at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:234)
Caused by: org.apache.solr.common.SolrException: java.io.FileNotFoundException: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/source_collection_shard1_replica1/data/tlog/tlog.0000000000000000007.1508611984948461568 (No such file or directory)
at org.apache.solr.update.CdcrTransactionLog.reopenOutputStream(CdcrTransactionLog.java:244)
at org.apache.solr.update.CdcrTransactionLog.incref(CdcrTransactionLog.java:173)
at org.apache.solr.update.UpdateLog.getRecentUpdates(UpdateLog.java:1078)
at org.apache.solr.update.UpdateLog.seedBucketsWithHighestVersion(UpdateLog.java:1578)
at org.apache.solr.update.UpdateLog.seedBucketsWithHighestVersion(UpdateLog.java:1610)
at org.apache.solr.core.SolrCore.seedVersionBuckets(SolrCore.java:866)
at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:526)
at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:227)
Caused by: java.io.FileNotFoundException: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/source_collection_shard1_replica1/data/tlog/tlog.0000000000000000007.1508611984948461568 (No such file or directory)
at java.io.RandomAccessFile.open0(Native Method)
at java.io.RandomAccessFile.open(RandomAccessFile.java:316)
at java.io.RandomAccessFile.<init>(RandomAccessFile.java:243)
at org.apache.solr.update.CdcrTransactionLog.reopenOutputStream(CdcrTransactionLog.java:236)
... 7 more
Build Log:
[...truncated 10629 lines...]
[junit4] Suite: org.apache.solr.cloud.CdcrReplicationHandlerTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/init-core-data-001
[junit4] 2> 542019 INFO (SUITE-CdcrReplicationHandlerTest-seed#[3C89B73603097DF3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
[junit4] 2> 542019 INFO (SUITE-CdcrReplicationHandlerTest-seed#[3C89B73603097DF3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /u_/rc
[junit4] 2> 542022 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 542022 INFO (Thread-1254) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 542022 INFO (Thread-1254) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 542122 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.ZkTestServer start zk server on port:54564
[junit4] 2> 542122 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 542123 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 542124 INFO (zkCallback-307-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14fcc5d name:ZooKeeperConnection Watcher:127.0.0.1:54564 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 542125 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 542125 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 542125 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 542126 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 542127 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 542127 INFO (zkCallback-308-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@dbfc3e name:ZooKeeperConnection Watcher:127.0.0.1:54564/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 542127 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 542128 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 542128 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 542128 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 542129 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 542129 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 542130 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-cdcr.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 542130 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 542131 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 542131 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 542132 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 542132 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 542132 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 542132 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 542133 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 542133 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 542133 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 542134 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 542134 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 542134 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 542135 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 542135 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 542136 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 542136 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 542136 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 542136 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 542137 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 542137 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 542138 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 542138 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 542139 INFO (zkCallback-309-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1aa17a1 name:ZooKeeperConnection Watcher:127.0.0.1:54564/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 542139 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 542139 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 542140 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTest
[junit4] 2> 542197 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1
[junit4] 2> 542198 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 542199 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13202c7{/u_/rc,null,AVAILABLE}
[junit4] 2> 542206 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1faad4c{SSL-http/1.1}{127.0.0.1:51217}
[junit4] 2> 542206 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.Server Started @543994ms
[junit4] 2> 542206 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard1, hostContext=/u_/rc, hostPort=51217, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores}
[junit4] 2> 542207 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 542207 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/'
[junit4] 2> 542216 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 542216 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 542217 INFO (zkCallback-310-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1fd0a96 name:ZooKeeperConnection Watcher:127.0.0.1:54564/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 542217 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 542218 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 542218 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 542219 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/solr.xml
[junit4] 2> 542222 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores
[junit4] 2> 542222 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoreContainer New CoreContainer 9898279
[junit4] 2> 542222 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/]
[junit4] 2> 542222 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/lib
[junit4] 2> 542222 WARN (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/lib).
[junit4] 2> 542227 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] 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> 542228 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 542229 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 542229 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 542229 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54564/solr
[junit4] 2> 542229 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 542230 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 542230 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 542231 INFO (zkCallback-312-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b197b0 name:ZooKeeperConnection Watcher:127.0.0.1:54564 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 542232 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 542232 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 542233 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 542234 INFO (zkCallback-313-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e37f9e name:ZooKeeperConnection Watcher:127.0.0.1:54564/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 542234 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 542234 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 542235 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 542236 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 542236 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 542237 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 542238 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 542238 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 542239 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 542239 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51217_u_%2Frc
[junit4] 2> 542239 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51217_u_%2Frc
[junit4] 2> 542240 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 542240 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 542241 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 542241 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000
[junit4] 2> 542241 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51217_u_%2Frc
[junit4] 2> 542242 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 542242 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer Overseer (id=94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) starting
[junit4] 2> 542243 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 542244 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 542245 INFO (OverseerCollectionProcessor-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
[junit4] 2> 542245 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 542245 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 542246 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 542247 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 542247 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores
[junit4] 2> 542247 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1, collection=tmp_collection, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 542248 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/
[junit4] 2> 542248 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 542248 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 542248 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 542248 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 542248 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 542248 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 542248 INFO (zkCallback-313-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 542249 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:51217/u_/rc",
[junit4] 2> "node_name":"127.0.0.1:51217_u_%2Frc",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 542249 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:51217/u_/rc",
[junit4] 2> "node_name":"127.0.0.1:51217_u_%2Frc",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 542249 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ClusterStateMutator building a new cName: tmp_collection
[junit4] 2> 542249 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 542250 INFO (zkCallback-313-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] 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> 542250 INFO (zkCallback-313-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
[junit4] 2> 543249 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 543249 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:tmp_collection
[junit4] 2> 543249 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController Creating collection in ZooKeeper:tmp_collection
[junit4] 2> 543249 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController Looking for collection configName
[junit4] 2> 543249 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController Only one config set found in zk - using it:conf1
[junit4] 2> 543250 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection
[junit4] 2> 543250 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/tmp_collection
[junit4] 2> 543250 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 543250 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/'
[junit4] 2> 543258 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 543261 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 543263 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 543279 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 543280 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 543284 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 543372 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 543373 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 543374 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 543376 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 543386 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 543386 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection tmp_collection
[junit4] 2> 543386 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory
[junit4] 2> 543386 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/], dataDir=[null]
[junit4] 2> 543386 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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@3a544a
[junit4] 2> 543387 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data
[junit4] 2> 543387 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data/index/
[junit4] 2> 543387 WARN (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 543388 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data/index
[junit4] 2> 543388 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8972083751870165]
[junit4] 2> 543442 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 543442 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 543442 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51217_u_%2Frc c:tmp_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-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data/
[junit4] 2> 543445 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "cdcr-processor-chain"
[junit4] 2> 543457 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 543460 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 543461 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 543462 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 543462 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 543469 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/cdcr,/update,/admin/file
[junit4] 2> 543469 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543475 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 543476 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543476 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 543476 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 543477 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=78.857421875, floorSegmentMB=1.3642578125, forceMergeDeletesPctAllowed=13.472462509962904, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 543477 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-001/cores/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 543477 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 543477 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5ad8e9[collection1] main
[junit4] 2> 543477 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/tmp_collection
[junit4] 2> 543478 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 543478 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543478 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 543478 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 543478 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 543478 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543479 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543479 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 543479 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 543481 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection/cdcr/state
[junit4] 2> 543482 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.CdcrBufferStateManager Created znode /collections/tmp_collection/cdcr/state/buffer
[junit4] 2> 543485 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.CdcrProcessStateManager Created znode /collections/tmp_collection/cdcr/state/process
[junit4] 2> 543494 INFO (searcherExecutor-1323-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ad8e9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 543494 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543494 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543494 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543494 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_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 1508611957086748672
[junit4] 2> 543496 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1508611957086748672
[junit4] 2> 543496 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 543496 INFO (coreLoadExecutor-1322-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 543497 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:51217/u_/rc collection:tmp_collection shard:shard1
[junit4] 2> 543497 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection/leader_elect/shard1/election
[junit4] 2> 543498 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/tmp_collection/leader_elect/shard1/election/94288247221518341-core_node1-n_0000000000
[junit4] 2> 543498 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 543499 INFO (zkCallback-313-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:51217/u_/rc/collection1/
[junit4] 2> 543499 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"tmp_collection"} current state version: 1
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:51217/u_/rc/collection1/ has no replicas
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:51217/u_/rc/collection1/ shard1
[junit4] 2> 543499 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection/leaders/shard1
[junit4] 2> 543500 INFO (zkCallback-313-thread-1-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.h.CdcrProcessStateManager Received new leader state @ tmp_collection:shard1
[junit4] 2> 543501 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 543501 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "base_url":"https://127.0.0.1:51217/u_/rc",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 543602 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] 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> 543603 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
[junit4] 2> 543651 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:51217/u_/rc/collection1/ and leader is https://127.0.0.1:51217/u_/rc/collection1/
[junit4] 2> 543651 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:51217/u_/rc
[junit4] 2> 543652 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 543652 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 543652 INFO (coreZkRegister-1316-thread-1-processing-n:127.0.0.1:51217_u_%2Frc x:collection1 s:shard1 c:tmp_collection r:core_node1) [n:127.0.0.1:51217_u_%2Frc c:tmp_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> 543652 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 543653 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:51217/u_/rc",
[junit4] 2> "node_name":"127.0.0.1:51217_u_%2Frc",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 543653 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:51217/u_/rc",
[junit4] 2> "node_name":"127.0.0.1:51217_u_%2Frc",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 543754 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] 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> 543754 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
[junit4] 2> 543796 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1
[junit4] 2> 543798 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 543798 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c5f63{/u_/rc,null,AVAILABLE}
[junit4] 2> 543801 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@12f88d1{SSL-http/1.1}{127.0.0.1:42564}
[junit4] 2> 543801 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.e.j.s.Server Started @545589ms
[junit4] 2> 543801 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard2, hostContext=/u_/rc, hostPort=42564, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores}
[junit4] 2> 543802 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 543802 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/'
[junit4] 2> 543810 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 543810 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 543811 INFO (zkCallback-314-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b9a409 name:ZooKeeperConnection Watcher:127.0.0.1:54564/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 543812 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 543812 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 543813 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 543813 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/solr.xml
[junit4] 2> 543816 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores
[junit4] 2> 543816 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoreContainer New CoreContainer 32806927
[junit4] 2> 543816 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/]
[junit4] 2> 543816 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.CoreContainer loading shared library: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/lib
[junit4] 2> 543816 WARN (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/lib).
[junit4] 2> 543820 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] 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> 543822 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 543823 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 543823 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 543823 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54564/solr
[junit4] 2> 543823 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 543823 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 543824 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 543824 INFO (zkCallback-316-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9ec90 name:ZooKeeperConnection Watcher:127.0.0.1:54564 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 543825 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 543825 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 543826 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 543840 INFO (zkCallback-317-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1bdf244 name:ZooKeeperConnection Watcher:127.0.0.1:54564/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 543841 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 543842 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 544844 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42564_u_%2Frc
[junit4] 2> 544844 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42564_u_%2Frc
[junit4] 2> 544845 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 544845 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94288247221518344-127.0.0.1:42564_u_%2Frc-n_0000000001
[junit4] 2> 544846 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000 to know if I could be the leader
[junit4] 2> 544846 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 544846 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 544847 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.CoresLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores
[junit4] 2> 544847 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1, collection=tmp_collection, absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 544847 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.CoresLocator Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/
[junit4] 2> 544847 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 544848 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 544848 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 544848 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 544848 INFO (TEST-CdcrReplicationHandlerTest.doTest-seed#[3C89B73603097DF3]) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 544848 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 544848 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 544849 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42564/u_/rc",
[junit4] 2> "node_name":"127.0.0.1:42564_u_%2Frc",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 544849 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:42564/u_/rc",
[junit4] 2> "node_name":"127.0.0.1:42564_u_%2Frc",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"tmp_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 544849 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=1
[junit4] 2> 544849 INFO (OverseerStateUpdate-94288247221518341-127.0.0.1:51217_u_%2Frc-n_0000000000) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 544950 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 544950 INFO (zkCallback-317-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 544951 INFO (zkCallback-313-thread-2-processing-n:127.0.0.1:51217_u_%2Frc) [n:127.0.0.1:51217_u_%2Frc ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
[junit4] 2> 544951 INFO (zkCallback-317-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
[junit4] 2> 545849 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 545849 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:tmp_collection
[junit4] 2> 545849 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 545849 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/tmp_collection
[junit4] 2> 545849 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 545849 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/'
[junit4] 2> 545856 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 545859 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 545861 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 545865 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 545866 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 545868 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 545939 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 545940 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 545940 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 545942 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 545949 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_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-trunk-Linux/solr/build/solr-core/test/J2
[junit4] 2> 545950 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection tmp_collection
[junit4] 2> 545950 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory
[junit4] 2> 545950 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/], dataDir=[null]
[junit4] 2> 545950 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3a544a
[junit4] 2> 545951 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data
[junit4] 2> 545951 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data/index/
[junit4] 2> 545951 WARN (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 545952 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data/index
[junit4] 2> 545952 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8972083751870165]
[junit4] 2> 545974 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 545974 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 545974 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data/
[junit4] 2> 545976 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "cdcr-processor-chain"
[junit4] 2> 545977 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 545978 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 545978 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 545979 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 545979 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 545992 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/cdcr,/update,/admin/file
[junit4] 2> 545992 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 545992 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 545992 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 545993 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 545993 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 545993 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=78.857421875, floorSegmentMB=1.3642578125, forceMergeDeletesPctAllowed=13.472462509962904, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 545994 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 545994 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 545994 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4f0afb[collection1] main
[junit4] 2> 545994 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/tmp_collection
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/tmp_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 545995 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 545996 INFO (coreLoadExecutor-1334-thread-1-processing-n:127.0.0.1:42564_u_%2Frc) [n:127.0.0.1:42564_u_%2Frc c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[...truncated too long message...]
ection/94288247221518341-core_node1-n_0000000000
[junit4] 2> 2 /solr/collections/tmp_collection/cdcr/state/buffer
[junit4] 2> 2 /solr/collections/tmp_collection/cdcr/state/process
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 60 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CdcrReplicationHandlerTest -Dtests.method=doTest -Dtests.seed=3C89B73603097DF3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=America/Cayenne -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 494s J2 | CdcrReplicationHandlerTest.doTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 330 seconds
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:172)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:128)
[junit4] > at org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish(BaseCdcrDistributedZkTest.java:465)
[junit4] > at org.apache.solr.cloud.BaseCdcrDistributedZkTest.restartServer(BaseCdcrDistributedZkTest.java:506)
[junit4] > at org.apache.solr.cloud.CdcrReplicationHandlerTest.doTestPartialReplicationWithTruncatedTlog(CdcrReplicationHandlerTest.java:145)
[junit4] > at org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest(CdcrReplicationHandlerTest.java:52)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3219, name=RecoveryThread-source_collection_shard1_replica1, state=RUNNABLE, group=TGRP-CdcrReplicationHandlerTest]
[junit4] > Caused by: org.apache.solr.common.cloud.ZooKeeperException:
[junit4] > at __randomizedtesting.SeedInfo.seed([3C89B73603097DF3]:0)
[junit4] > at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:234)
[junit4] > Caused by: org.apache.solr.common.SolrException: java.io.FileNotFoundException: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/source_collection_shard1_replica1/data/tlog/tlog.0000000000000000007.1508611984948461568 (No such file or directory)
[junit4] > at org.apache.solr.update.CdcrTransactionLog.reopenOutputStream(CdcrTransactionLog.java:244)
[junit4] > at org.apache.solr.update.CdcrTransactionLog.incref(CdcrTransactionLog.java:173)
[junit4] > at org.apache.solr.update.UpdateLog.getRecentUpdates(UpdateLog.java:1078)
[junit4] > at org.apache.solr.update.UpdateLog.seedBucketsWithHighestVersion(UpdateLog.java:1578)
[junit4] > at org.apache.solr.update.UpdateLog.seedBucketsWithHighestVersion(UpdateLog.java:1610)
[junit4] > at org.apache.solr.core.SolrCore.seedVersionBuckets(SolrCore.java:866)
[junit4] > at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:526)
[junit4] > at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:227)
[junit4] > Caused by: java.io.FileNotFoundException: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/source_collection_shard1_replica1/data/tlog/tlog.0000000000000000007.1508611984948461568 (No such file or directory)
[junit4] > at java.io.RandomAccessFile.open0(Native Method)
[junit4] > at java.io.RandomAccessFile.open(RandomAccessFile.java:316)
[junit4] > at java.io.RandomAccessFile.<init>(RandomAccessFile.java:243)
[junit4] > at org.apache.solr.update.CdcrTransactionLog.reopenOutputStream(CdcrTransactionLog.java:236)
[junit4] > ... 7 moreThrowable #3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3351, name=RecoveryThread-source_collection_shard1_replica1, state=RUNNABLE, group=TGRP-CdcrReplicationHandlerTest]
[junit4] > Caused by: org.apache.solr.common.cloud.ZooKeeperException:
[junit4] > at __randomizedtesting.SeedInfo.seed([3C89B73603097DF3]:0)
[junit4] > at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:234)
[junit4] > Caused by: org.apache.solr.common.SolrException: java.io.FileNotFoundException: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/source_collection_shard1_replica1/data/tlog/tlog.0000000000000000007.1508612052542816256 (No such file or directory)
[junit4] > at org.apache.solr.update.CdcrTransactionLog.reopenOutputStream(CdcrTransactionLog.java:244)
[junit4] > at org.apache.solr.update.CdcrTransactionLog.incref(CdcrTransactionLog.java:173)
[junit4] > at org.apache.solr.update.UpdateLog.getRecentUpdates(UpdateLog.java:1078)
[junit4] > at org.apache.solr.update.UpdateLog.seedBucketsWithHighestVersion(UpdateLog.java:1578)
[junit4] > at org.apache.solr.update.UpdateLog.seedBucketsWithHighestVersion(UpdateLog.java:1610)
[junit4] > at org.apache.solr.core.SolrCore.seedVersionBuckets(SolrCore.java:866)
[junit4] > at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:526)
[junit4] > at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:227)
[junit4] > Caused by: java.io.FileNotFoundException: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001/jetty-002/cores/source_collection_shard1_replica1/data/tlog/tlog.0000000000000000007.1508612052542816256 (No such file or directory)
[junit4] > at java.io.RandomAccessFile.open0(Native Method)
[junit4] > at java.io.RandomAccessFile.open(RandomAccessFile.java:316)
[junit4] > at java.io.RandomAccessFile.<init>(RandomAccessFile.java:243)
[junit4] > at org.apache.solr.update.CdcrTransactionLog.reopenOutputStream(CdcrTransactionLog.java:236)
[junit4] > ... 7 more
[junit4] 2> 1036478 INFO (SUITE-CdcrReplicationHandlerTest-seed#[3C89B73603097DF3]-worker) [n:127.0.0.1:51217_u_%2Frc c:source_collection s:shard1 r:core_node2 x:source_collection_shard1_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.CdcrReplicationHandlerTest_3C89B73603097DF3-001
[junit4] 2> Aug 04, 2015 9:49:29 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {_version_=FSTOrd50, id=FST50}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=no, timezone=America/Cayenne
[junit4] 2> NOTE: Linux 3.16.0-44-generic i386/Oracle Corporation 1.8.0_51 (32-bit)/cpus=12,threads=1,free=40214824,total=282279936
[junit4] 2> NOTE: All tests run in this JVM: [TestRandomRequestDistribution, OutOfBoxZkACLAndCredentialsProvidersTest, AutoCommitTest, OpenExchangeRatesOrgProviderTest, SolrTestCaseJ4Test, OutputWriterTest, TestFileDictionaryLookup, TestDefaultSimilarityFactory, DateRangeFieldTest, OverseerRolesTest, TestSurroundQueryParser, RecoveryZkTest, TestInitQParser, TestStressRecovery, TestNRTOpen, DistributedFacetPivotLongTailTest, TestQueryUtils, TestQuerySenderNoQuery, TestReplicationHandlerBackup, SaslZkACLProviderTest, TestPerFieldSimilarity, TestBM25SimilarityFactory, TestXIncludeConfig, TestExactSharedStatsCache, DeleteShardTest, TestRestManager, TestConfigReload, TestPartialUpdateDeduplication, ShardSplitTest, FullSolrCloudDistribCmdsTest, AsyncCallRequestStatusResponseTest, TestFieldCollectionResource, TestHighlightDedupGrouping, TermVectorComponentDistributedTest, CdcrReplicationHandlerTest]
[junit4] Completed [345/531] on J2 in 494.73s, 1 test, 1 error <<< FAILURES!
[...truncated 599 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:716: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:660: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:230: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:514: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1429: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:984: There were test failures: 531 suites, 2104 tests, 1 error, 65 ignored (30 assumptions)
Total time: 52 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any