Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19185/
Java: 32bit/jdk-9-ea+160 -server -XX:+UseG1GC

1 tests failed.
FAILED:  org.apache.solr.cloud.OnlyLeaderIndexesTest.test

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([2534C8B7EF9747F4:AD60F76D416B2A0C]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at org.junit.Assert.assertNotNull(Assert.java:537)
        at 
org.apache.solr.cloud.OnlyLeaderIndexesTest.outOfOrderDBQWithInPlaceUpdatesTest(OnlyLeaderIndexesTest.java:295)
        at 
org.apache.solr.cloud.OnlyLeaderIndexesTest.test(OnlyLeaderIndexesTest.java:78)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:547)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        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:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 11094 lines...]
   [junit4] Suite: org.apache.solr.cloud.OnlyLeaderIndexesTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/init-core-data-001
   [junit4]   2> 266534 WARN  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 266534 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 266536 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 266538 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001
   [junit4]   2> 266539 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 266539 INFO  (Thread-552) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 266539 INFO  (Thread-552) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 266639 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:43425
   [junit4]   2> 266647 INFO  (jetty-launcher-375-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 266647 INFO  (jetty-launcher-375-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 266649 INFO  (jetty-launcher-375-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 266649 INFO  (jetty-launcher-375-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@12f8743{/solr,null,AVAILABLE}
   [junit4]   2> 266650 INFO  (jetty-launcher-375-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@f65f90{/solr,null,AVAILABLE}
   [junit4]   2> 266650 INFO  (jetty-launcher-375-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1ecdd9f{/solr,null,AVAILABLE}
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1deb284{SSL,[ssl, 
http/1.1]}{127.0.0.1:33527}
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@18912a4{SSL,[ssl, 
http/1.1]}{127.0.0.1:36713}
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.e.j.s.Server Started @268774ms
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.e.j.s.Server Started @268774ms
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=33527}
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36713}
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@12834be{SSL,[ssl, 
http/1.1]}{127.0.0.1:39165}
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.e.j.s.Server Started @268774ms
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39165}
   [junit4]   2> 266659 ERROR (jetty-launcher-375-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 266659 ERROR (jetty-launcher-375-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 266659 ERROR (jetty-launcher-375-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-16T17:07:11.682666Z
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-16T17:07:11.682754Z
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 266659 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-16T17:07:11.682832Z
   [junit4]   2> 266662 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 266662 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 266662 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 266682 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43425/solr
   [junit4]   2> 266683 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43425/solr
   [junit4]   2> 266685 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43425/solr
   [junit4]   2> 266697 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 266697 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 266698 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33527_solr
   [junit4]   2> 266698 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39165_solr
   [junit4]   2> 266699 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.Overseer Overseer 
(id=97627932692512777-127.0.0.1:33527_solr-n_0000000000) starting
   [junit4]   2> 266699 INFO  (zkCallback-392-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 266699 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 266699 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 266701 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 266702 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36713_solr
   [junit4]   2> 266702 INFO  (zkCallback-392-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 266703 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 266703 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 266709 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:33527_solr
   [junit4]   2> 266709 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 266710 INFO  (zkCallback-392-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 266710 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 266874 INFO  (jetty-launcher-375-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node2/.
   [junit4]   2> 266897 INFO  (jetty-launcher-375-thread-3) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node3/.
   [junit4]   2> 266944 INFO  (jetty-launcher-375-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/.
   [junit4]   2> 267078 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 267078 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43425/solr ready
   [junit4]   2> 267162 INFO  (qtp4794588-2316) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=3&collection.configName=config&maxShardsPerNode=1&name=collection1&realtimeReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 267163 INFO  (OverseerThreadFactory-908-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Create collection collection1
   [junit4]   2> 267279 INFO  (qtp4794588-2318) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica3&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2
   [junit4]   2> 267342 INFO  (qtp25844233-2333) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2
   [junit4]   2> 267353 INFO  (qtp26429884-2317) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2
   [junit4]   2> 267456 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 267456 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 267456 INFO  (zkCallback-392-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 268289 INFO  (qtp4794588-2318) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.0.0
   [junit4]   2> 268297 INFO  (qtp4794588-2318) [    ] o.a.s.s.IndexSchema 
[collection1_shard1_replica3] Schema name=minimal
   [junit4]   2> 268303 INFO  (qtp4794588-2318) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 268304 INFO  (qtp4794588-2318) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica3' using configuration from 
collection collection1
   [junit4]   2> 268308 INFO  (qtp4794588-2318) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 268309 INFO  (qtp4794588-2318) [    ] o.a.s.c.SolrCore 
[[collection1_shard1_replica3] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node3/collection1_shard1_replica3],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node3/./collection1_shard1_replica3/data/]
   [junit4]   2> 268358 INFO  (qtp25844233-2333) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 268380 INFO  (qtp26429884-2317) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 268386 INFO  (qtp25844233-2333) [    ] o.a.s.s.IndexSchema 
[collection1_shard1_replica1] Schema name=minimal
   [junit4]   2> 268387 INFO  (qtp25844233-2333) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 268388 INFO  (qtp25844233-2333) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica1' using configuration from 
collection collection1
   [junit4]   2> 268389 INFO  (qtp25844233-2333) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 268389 INFO  (qtp25844233-2333) [    ] o.a.s.c.SolrCore 
[[collection1_shard1_replica1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/collection1_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/./collection1_shard1_replica1/data/]
   [junit4]   2> 268392 INFO  (qtp26429884-2317) [    ] o.a.s.s.IndexSchema 
[collection1_shard1_replica2] Schema name=minimal
   [junit4]   2> 268401 INFO  (qtp26429884-2317) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 268405 INFO  (qtp26429884-2317) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica2' using configuration from 
collection collection1
   [junit4]   2> 268406 INFO  (qtp26429884-2317) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 268406 INFO  (qtp26429884-2317) [    ] o.a.s.c.SolrCore 
[[collection1_shard1_replica2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node2/collection1_shard1_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node2/./collection1_shard1_replica2/data/]
   [junit4]   2> 268864 INFO  (qtp4794588-2318) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 268864 INFO  (qtp4794588-2318) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 268865 INFO  (qtp4794588-2318) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 268865 INFO  (qtp4794588-2318) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 268867 INFO  (qtp4794588-2318) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@196b00f[collection1_shard1_replica3] main]
   [junit4]   2> 268868 INFO  (qtp4794588-2318) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 268868 INFO  (qtp4794588-2318) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 268868 INFO  (qtp4794588-2318) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 268869 INFO  (searcherExecutor-915-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica3] Registered new searcher 
Searcher@196b00f[collection1_shard1_replica3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 268869 INFO  (qtp4794588-2318) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1562046925522337792
   [junit4]   2> 268872 INFO  (qtp4794588-2318) [    ] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=3 found=1 timeoutin=9999ms
   [junit4]   2> 268914 INFO  (qtp26429884-2317) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 268914 INFO  (qtp26429884-2317) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 268916 INFO  (qtp26429884-2317) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 268916 INFO  (qtp26429884-2317) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 268919 INFO  (qtp26429884-2317) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1503b18[collection1_shard1_replica2] main]
   [junit4]   2> 268920 INFO  (qtp26429884-2317) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 268920 INFO  (qtp26429884-2317) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 268920 INFO  (qtp26429884-2317) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 268921 INFO  (searcherExecutor-917-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher 
Searcher@1503b18[collection1_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 268921 INFO  (qtp26429884-2317) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1562046925576863744
   [junit4]   2> 268924 INFO  (qtp25844233-2333) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 268924 INFO  (qtp25844233-2333) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 268925 INFO  (qtp25844233-2333) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 268925 INFO  (qtp25844233-2333) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 268927 INFO  (qtp25844233-2333) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@652540[collection1_shard1_replica1] 
main]
   [junit4]   2> 268927 INFO  (qtp25844233-2333) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/config
   [junit4]   2> 268927 INFO  (qtp25844233-2333) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/config
   [junit4]   2> 268928 INFO  (qtp25844233-2333) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 268928 INFO  (searcherExecutor-916-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher 
Searcher@652540[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 268928 INFO  (qtp25844233-2333) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1562046925584203776
   [junit4]   2> 268974 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 268974 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 268974 INFO  (zkCallback-392-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269373 INFO  (qtp4794588-2318) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 269373 INFO  (qtp4794588-2318) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 269373 INFO  (qtp4794588-2318) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 269373 INFO  (qtp4794588-2318) [    ] o.a.s.u.PeerSync 
PeerSync: core=collection1_shard1_replica3 url=https://127.0.0.1:33527/solr 
START replicas=[https://127.0.0.1:36713/solr/collection1_shard1_replica1/, 
https://127.0.0.1:39165/solr/collection1_shard1_replica2/] nUpdates=100
   [junit4]   2> 269375 INFO  (qtp4794588-2318) [    ] o.a.s.u.PeerSync 
PeerSync: core=collection1_shard1_replica3 url=https://127.0.0.1:33527/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 269382 INFO  (qtp25844233-2341) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 269386 INFO  (qtp26429884-2319) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 269386 INFO  (qtp4794588-2318) [    ] o.a.s.c.SyncStrategy 
Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 269386 INFO  (qtp4794588-2318) [    ] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 269386 INFO  (qtp4794588-2318) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 269388 INFO  (qtp4794588-2318) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/ shard1
   [junit4]   2> 269491 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269491 INFO  (zkCallback-392-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269491 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269540 INFO  (qtp4794588-2318) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 269541 INFO  (qtp4794588-2318) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica3&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2262
   [junit4]   2> 269642 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269642 INFO  (zkCallback-392-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269642 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 269930 INFO  (qtp26429884-2317) [    ] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
   [junit4]   2> 269930 WARN  (qtp26429884-2317) [    ] 
o.a.s.h.ReplicationHandler SolrCloud is enabled for core 
collection1_shard1_replica2 but so is old-style replication. Make sure you 
intend this behavior, it usually indicates a mis-configuration. Master setting 
is false and slave setting is true
   [junit4]   2> 269931 INFO  (qtp26429884-2317) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica2&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2578
   [junit4]   2> 269934 INFO  (qtp25844233-2333) [    ] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
   [junit4]   2> 269934 WARN  (qtp25844233-2333) [    ] 
o.a.s.h.ReplicationHandler SolrCloud is enabled for core 
collection1_shard1_replica1 but so is old-style replication. Make sure you 
intend this behavior, it usually indicates a mis-configuration. Master setting 
is false and slave setting is true
   [junit4]   2> 269934 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 269937 INFO  (qtp25844233-2333) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=config&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=1&collection=collection1&shard=shard1&wt=javabin&version=2}
 status=0 QTime=2595
   [junit4]   2> 269942 INFO  (qtp4794588-2316) [    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 269942 INFO  (qtp4794588-2318) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 269942 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 269942 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 269942 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 269942 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 270039 INFO  (zkCallback-388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 270039 INFO  (zkCallback-392-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 270039 INFO  (zkCallback-391-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 270670 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 270676 INFO  (qtp4794588-2318) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 270677 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 270677 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 270677 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 270677 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 270942 INFO  (qtp4794588-2316) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={replicationFactor=3&collection.configName=config&maxShardsPerNode=1&name=collection1&realtimeReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=3780
   [junit4]   2> 270942 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):30
   [junit4]   2> 270943 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 270958 INFO  
(TEST-OnlyLeaderIndexesTest.test-seed#[2534C8B7EF9747F4]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 270976 INFO  (qtp26429884-2329) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[1
 (1562046927714910208), 2 (1562046927718055936), 3 (1562046927718055937), 4 
(1562046927718055938)]} 0 10
   [junit4]   2> 270977 INFO  (qtp25844233-2335) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[1
 (1562046927714910208), 2 (1562046927718055936), 3 (1562046927718055937), 4 
(1562046927718055938)]} 0 12
   [junit4]   2> 270977 INFO  (qtp4794588-2326) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[1 (1562046927714910208), 2 
(1562046927718055936), 3 (1562046927718055937), 4 (1562046927718055938)]} 0 16
   [junit4]   2> 270999 INFO  (qtp26429884-2331) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=1&wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 271001 INFO  (qtp26429884-2329) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271001 INFO  (qtp26429884-2321) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271002 INFO  (qtp26429884-2317) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271017 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=1&wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 271017 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271018 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271019 INFO  (qtp4794588-2318) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271026 INFO  (qtp25844233-2341) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=1&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271026 INFO  (qtp25844233-2335) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271027 INFO  (qtp25844233-2336) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271029 INFO  (qtp25844233-2333) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271032 INFO  (qtp26429884-2319) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{delete=[1
 (-1562046927789359104)]} 0 0
   [junit4]   2> 271032 INFO  (qtp25844233-2340) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{delete=[1
 (-1562046927789359104)]} 0 0
   [junit4]   2> 271033 INFO  (qtp4794588-2330) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{delete=[1 (-1562046927789359104)]} 0 
2
   [junit4]   2> 271065 INFO  (qtp4794588-2318) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1236b69[collection1_shard1_replica3] realtime]
   [junit4]   2> 271069 INFO  (qtp4794588-2318) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@12ea7e2[collection1_shard1_replica3] realtime]
   [junit4]   2> 271070 INFO  (qtp26429884-2317) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&_version_=-1562046927791456256&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{deleteByQuery=id:2
 (-1562046927791456256)} 0 0
   [junit4]   2> 271071 INFO  (qtp25844233-2336) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&_version_=-1562046927791456256&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{deleteByQuery=id:2
 (-1562046927791456256)} 0 1
   [junit4]   2> 271071 INFO  (qtp4794588-2318) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{deleteByQuery=id:2 
(-1562046927791456256)} 0 37
   [junit4]   2> 271083 INFO  (qtp26429884-2317) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271084 INFO  (qtp26429884-2323) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271084 INFO  (qtp26429884-2319) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271110 INFO  (qtp25844233-2336) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=2&wt=javabin&version=2} status=0 QTime=17
   [junit4]   2> 271112 INFO  (qtp25844233-2337) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=3&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271113 INFO  (qtp25844233-2340) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/get 
params={q=*:*&distrib=false&qt=/get&id=4&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 271118 INFO  (qtp4794588-2316) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1562046927880585216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 271118 INFO  (qtp4794588-2316) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@389384 
commitCommandVersion:1562046927880585216
   [junit4]   2> 271126 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 271128 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 271167 INFO  
(OverseerCollectionConfigSetProcessor-97627932692512777-127.0.0.1:33527_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 271528 INFO  (qtp4794588-2316) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1c6f839[collection1_shard1_replica3] main]
   [junit4]   2> 271528 INFO  (qtp4794588-2316) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 271529 INFO  (searcherExecutor-915-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica3] Registered new searcher 
Searcher@1c6f839[collection1_shard1_replica3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4/2:delGen=2)))}
   [junit4]   2> 271529 INFO  (qtp4794588-2316) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 410
   [junit4]   2> 271674 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=37
   [junit4]   2> 271675 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=5
   [junit4]   2> 272190 INFO  (qtp4794588-2330) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
   [junit4]   2> 272191 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 272703 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 272705 INFO  (qtp4794588-2330) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 272934 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 272934 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 272935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 272935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 1489684036141
   [junit4]   2> 272935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 272935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 272935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 272940 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=4
   [junit4]   2> 272940 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 16
   [junit4]   2> 272943 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/collection1_shard1_replica1/data/index.20170317040717963
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 272944 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_2.liv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272946 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272948 INFO  (qtp4794588-2330) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272950 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.pos&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272975 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272991 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272992 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 272994 INFO  (qtp4794588-2330) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273120 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Asserting_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273122 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.tix&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273125 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273127 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273129 INFO  (qtp4794588-2330) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.tbk&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273131 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273135 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273136 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273137 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2493) : 0 secs (null bytes/sec) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/collection1_shard1_replica1/data/index.20170317040717963
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 273215 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 273217 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
   [junit4]   2> 273670 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 273671 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 273671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 273671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 1489684036141
   [junit4]   2> 273671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 273671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 273671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 273675 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=3
   [junit4]   2> 273675 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 16
   [junit4]   2> 273678 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node2/collection1_shard1_replica2/data/index.20170317040718698
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 273694 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_2.liv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273695 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273697 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273698 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.pos&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273699 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273701 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273702 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273703 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273705 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Asserting_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273707 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.tix&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273708 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273709 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273711 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_FSTOrd50_0.tbk&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273712 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273713 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273715 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 273715 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2493) : 0 secs (null bytes/sec) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node2/collection1_shard1_replica2/data/index.20170317040718698
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 273727 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 273729 INFO  (qtp4794588-2316) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 273986 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 273989 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@118e4aa[collection1_shard1_replica1] main]
   [junit4]   2> 273989 INFO  (searcherExecutor-916-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher 
Searcher@118e4aa[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4/2:delGen=2)))}
   [junit4]   2> 274239 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 274245 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 274246 INFO  (qtp25844233-2335) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3130
   [junit4]   2> 274514 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 274516 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@caa717[collection1_shard1_replica2] 
main]
   [junit4]   2> 274516 INFO  (searcherExecutor-917-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica2] Registered new searcher 
Searcher@caa717[collection1_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4/2:delGen=2)))}
   [junit4]   2> 274750 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 274751 INFO  (qtp26429884-2331) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3636
   [junit4]   2> 274751 INFO  (qtp4794588-2328) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update 
params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{commit=} 0 
3637
   [junit4]   2> 274757 INFO  (qtp26429884-2317) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica2]  webapp=/solr path=/select 
params={q=*:*&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=0
   [junit4]   2> 274765 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/select 
params={q=*:*&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=0
   [junit4]   2> 274771 INFO  (qtp25844233-2336) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica1]  webapp=/solr path=/select 
params={q=*:*&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=0
   [junit4]   2> 274778 INFO  (qtp26429884-2323) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[15
 (1562046931714179072)]} 0 0
   [junit4]   2> 274778 INFO  (qtp25844233-2335) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[15
 (1562046931714179072)]} 0 1
   [junit4]   2> 274778 INFO  (qtp4794588-2426) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[15 (1562046931714179072)]} 0 4
   [junit4]   2> 274780 INFO  (qtp26429884-2317) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[16
 (1562046931719421952)]} 0 0
   [junit4]   2> 274780 INFO  (qtp25844233-2336) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[16
 (1562046931719421952)]} 0 0
   [junit4]   2> 274780 INFO  (qtp4794588-2316) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[16 (1562046931719421952)]} 0 1
   [junit4]   2> 274782 INFO  (qtp26429884-2331) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[17
 (1562046931721519104)]} 0 0
   [junit4]   2> 274782 INFO  (qtp25844233-2340) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[17
 (1562046931721519104)]} 0 0
   [junit4]   2> 274782 INFO  (qtp4794588-2322) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[17 (1562046931721519104)]} 0 1
   [junit4]   2> 274784 INFO  (qtp26429884-2319) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[18
 (1562046931723616256)]} 0 1
   [junit4]   2> 274785 INFO  (qtp25844233-2337) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[18
 (1562046931723616256)]} 0 1
   [junit4]   2> 274785 INFO  (qtp4794588-2425) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[18 (1562046931723616256)]} 0 2
   [junit4]   2> 274786 INFO  (qtp26429884-2323) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[19
 (1562046931726761984)]} 0 0
   [junit4]   2> 274787 INFO  (qtp25844233-2336) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[19
 (1562046931726761984)]} 0 0
   [junit4]   2> 274787 INFO  (qtp4794588-2320) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[19 (1562046931726761984)]} 0 1
   [junit4]   2> 274789 INFO  (qtp26429884-2317) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[20
 (1562046931728859136)]} 0 0
   [junit4]   2> 274789 INFO  (qtp25844233-2340) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[20
 (1562046931728859136)]} 0 0
   [junit4]   2> 274789 INFO  (qtp4794588-2330) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[20 (1562046931728859136)]} 0 1
   [junit4]   2> 274790 INFO  (qtp26429884-2319) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[21
 (1562046931730956288)]} 0 0
   [junit4]   2> 274791 INFO  (qtp25844233-2337) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[21
 (1562046931730956288)]} 0 0
   [junit4]   2> 274791 INFO  (qtp4794588-2426) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[21 (1562046931730956288)]} 0 1
   [junit4]   2> 274792 INFO  (qtp26429884-2331) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[22
 (1562046931732004864)]} 0 0
   [junit4]   2> 274792 INFO  (qtp25844233-2336) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[22
 (1562046931732004864)]} 0 0
   [junit4]   2> 274792 INFO  (qtp4794588-2316) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[22 (1562046931732004864)]} 0 1
   [junit4]   2> 274794 INFO  (qtp26429884-2323) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[23
 (1562046931734102016)]} 0 0
   [junit4]   2> 274794 INFO  (qtp25844233-2340) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[23
 (1562046931734102016)]} 0 0
   [junit4]   2> 274794 INFO  (qtp4794588-2322) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[23 (1562046931734102016)]} 0 1
   [junit4]   2> 274795 INFO  (qtp26429884-2317) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[24
 (1562046931736199168)]} 0 0
   [junit4]   2> 274797 INFO  (qtp25844233-2337) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[24
 (1562046931736199168)]} 0 1
   [junit4]   2> 274797 INFO  (qtp4794588-2425) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[24 (1562046931736199168)]} 0 2
   [junit4]   2> 274798 INFO  (qtp26429884-2319) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica2]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[25
 (1562046931739344896)]} 0 0
   [junit4]   2> 274799 INFO  (qtp25844233-2336) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&wt=javabin&version=2}{add=[25
 (1562046931739344896)]} 0 0
   [junit4]   2> 274799 INFO  (qtp4794588-2320) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update params={wt=javabin&version=2}{add=[25 (1562046931739344896)]} 0 1
   [junit4]   2> 274803 INFO  (qtp4794588-2328) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1562046931744587776,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 274803 INFO  (qtp4794588-2328) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@389384 
commitCommandVersion:1562046931744587776
   [junit4]   2> 274862 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=28
   [junit4]   2> 274866 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
   [junit4]   2> 275066 INFO  (qtp4794588-2328) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1d0d8a4[collection1_shard1_replica3] main]
   [junit4]   2> 275066 INFO  (searcherExecutor-915-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica3] Registered new searcher 
Searcher@1d0d8a4[collection1_shard1_replica3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4/2:delGen=2)
 Uninverting(_1(7.0.0):C11)))}
   [junit4]   2> 275066 INFO  (qtp4794588-2328) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 275066 INFO  (qtp4794588-2328) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica3]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 263
   [junit4]   2> 275374 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=4
   [junit4]   2> 275380 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=5
   [junit4]   2> 275887 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 275893 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 275934 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 275934 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 275935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 275935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 1489684039826
   [junit4]   2> 275935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 275935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 1489684036141
   [junit4]   2> 275935 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 275941 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=6
   [junit4]   2> 275942 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 30
   [junit4]   2> 275948 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/collection1_shard1_replica1/data/index.20170317040720965
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 275949 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_2.liv because it already exists
   [junit4]   2> 275949 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 275949 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.doc because it already 
exists
   [junit4]   2> 275949 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.pos because it already 
exists
   [junit4]   2> 275949 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 275949 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 275950 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 275950 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Lucene70_0.dvm because it already 
exists
   [junit4]   2> 275950 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.dvd because it 
already exists
   [junit4]   2> 275950 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.tix because it already 
exists
   [junit4]   2> 275950 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Lucene70_0.dvd because it already 
exists
   [junit4]   2> 275950 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.dvm because it 
already exists
   [junit4]   2> 275951 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.tbk because it already 
exists
   [junit4]   2> 275951 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 275951 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 275952 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275954 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275964 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275965 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.tix&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275975 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275977 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275987 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.tbk&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275988 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275991 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 275993 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.pos&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276006 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_Asserting_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276009 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276010 INFO  (qtp4794588-2326) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276020 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_Lucene70_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276022 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276037 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2685) : 0 secs (null bytes/sec) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node1/collection1_shard1_replica1/data/index.20170317040720965
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 276205 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 276208 INFO  (indexFetcher-933-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1b95389[collection1_shard1_replica1] main]
   [junit4]   2> 276208 INFO  (searcherExecutor-916-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica1] Registered new searcher 
Searcher@1b95389[collection1_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C4/2:delGen=2)
 Uninverting(_1(7.0.0):C11)))}
   [junit4]   2> 276397 INFO  (qtp4794588-2426) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
   [junit4]   2> 276399 INFO  (qtp25844233-2340) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1]  webapp=/solr 
path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33527/solr/collection1_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1596
   [junit4]   2> 276403 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
   [junit4]   2> 276670 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Updated masterUrl to 
https://127.0.0.1:33527/solr/collection1_shard1_replica3/
   [junit4]   2> 276671 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 276671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 276671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 1489684039826
   [junit4]   2> 276671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 276671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's version: 1489684036141
   [junit4]   2> 276671 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 276677 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=5
   [junit4]   2> 276678 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 30
   [junit4]   2> 276683 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
NIOFSDirectory@/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001/tempDir-001/node2/collection1_shard1_replica2/data/index.20170317040721701
 lockFactory=org.apache.lucene.store.NativeFSLockFactory@17915e6
   [junit4]   2> 276683 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_2.liv because it already exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.doc because it already 
exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.pos because it already 
exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 276684 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Lucene70_0.dvm because it already 
exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.dvd because it 
already exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.tix because it already 
exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Lucene70_0.dvd because it already 
exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_Asserting_0.dvm because it 
already exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_FSTOrd50_0.tbk because it already 
exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 276685 INFO  (indexFetcher-931-thread-1) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 276686 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276688 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_Lucene70_0.dvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276689 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276690 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.tix&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276700 INFO  (qtp4794588-2320) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276701 INFO  (qtp4794588-2425) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276705 INFO  (qtp4794588-2322) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.tbk&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276706 INFO  (qtp4794588-2328) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_Asserting_0.dvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 276707 INFO  (qtp4794588-2423) [    ] o.a.s.c.S.Request 
[collection1_shard1_replica3]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_FSTOrd50_0.doc&checksum=true&wt=filestream&command=filecontent

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

flect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:547)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 385885 INFO  (jetty-closer-376-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@1be7156{SSL,[ssl, 
http/1.1]}{127.0.0.1:39165}
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=9111214
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-3) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@490355{SSL,[ssl, 
http/1.1]}{127.0.0.1:36713}
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-2) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@1d52c76{SSL,[ssl, 
http/1.1]}{127.0.0.1:33527}
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-3) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=23587050
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-2) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=3063435
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 385886 INFO  (jetty-closer-376-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 385887 INFO  (jetty-closer-376-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 385888 INFO  (coreCloseExecutor-1077-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1defaf7
   [junit4]   2> 385888 INFO  (jetty-closer-376-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 385888 INFO  (jetty-closer-376-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 385888 INFO  (coreCloseExecutor-1078-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@10d2ce2
   [junit4]   2> 385888 INFO  (coreCloseExecutor-1079-thread-1) [    ] 
o.a.s.c.SolrCore [collection1_shard1_replica3]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1945d4c
   [junit4]   2> 385888 INFO  (zkCallback-433-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 385888 INFO  (zkCallback-426-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 385888 INFO  (zkCallback-418-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 385889 INFO  (coreCloseExecutor-1079-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 385889 INFO  (coreCloseExecutor-1079-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@e14374 commitCommandVersion:0
   [junit4]   2> 385892 INFO  (coreCloseExecutor-1077-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1.shard1.replica2, tag=31390455
   [junit4]   2> 385893 INFO  (jetty-closer-376-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=97627932692512788-127.0.0.1:39165_solr-n_0000000005) closing
   [junit4]   2> 385893 INFO  
(OverseerStateUpdate-97627932692512788-127.0.0.1:39165_solr-n_0000000005) [    
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39165_solr
   [junit4]   2> 385901 INFO  (zkCallback-433-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 385901 INFO  (zkCallback-426-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 385901 INFO  (zkCallback-426-thread-2) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33527_solr
   [junit4]   2> 385902 INFO  (coreCloseExecutor-1078-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1.shard1.replica1, tag=17640674
   [junit4]   2> 385903 INFO  (zkCallback-426-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 385941 INFO  (coreCloseExecutor-1079-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1.shard1.replica3, tag=26500428
   [junit4]   2> 385942 WARN  (zkCallback-426-thread-2) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 385943 INFO  (jetty-closer-376-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1f06135{/solr,null,UNAVAILABLE}
   [junit4]   2> 387401 WARN  (zkCallback-418-thread-1) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 387402 INFO  (jetty-closer-376-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@783889{/solr,null,UNAVAILABLE}
   [junit4]   2> 387403 WARN  (zkCallback-433-thread-1) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 387403 INFO  (jetty-closer-376-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1481b57{/solr,null,UNAVAILABLE}
   [junit4]   2> 387405 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:43425 43425
   [junit4]   2> 387434 INFO  (Thread-552) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:43425 43425
   [junit4]   2> 387434 WARN  (Thread-552) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        8       /solr/security.json
   [junit4]   2>        8       /solr/configs/config
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        8       /solr/collections/collection1/state.json
   [junit4]   2>        3       
/solr/overseer_elect/election/97627932692512776-127.0.0.1:36713_solr-n_0000000002
   [junit4]   2>        3       
/solr/collections/collection1/leader_elect/shard1/election/97627932692512776-core_node2-n_0000000002
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard1/election/97627932692512777-core_node1-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/97627932692512777-127.0.0.1:33527_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        117     /solr/overseer/collection-queue-work
   [junit4]   2>        40      /solr/overseer/queue
   [junit4]   2>        19      /solr/overseer/queue-work
   [junit4]   2>        9       /solr/collections
   [junit4]   2>        4       /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 387434 INFO  
(SUITE-OnlyLeaderIndexesTest-seed#[2534C8B7EF9747F4]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OnlyLeaderIndexesTest_2534C8B7EF9747F4-001
   [junit4]   2> Mar 16, 2017 5:09:12 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{title_s=PostingsFormat(name=MockRandom), id=FSTOrd50}, 
docValues:{_version_=DocValuesFormat(name=Asserting), 
id=DocValuesFormat(name=Lucene70), 
inplace_updatable_int=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=477, 
maxMBSortInHeap=7.093007753862301, sim=RandomSimilarity(queryNorm=true): {}, 
locale=en-PK, timezone=Pacific/Guadalcanal
   [junit4]   2> NOTE: Linux 4.4.0-66-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=1,free=246082880,total=411041792
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestExclusionRuleCollectionAccess, TestRequestForwarding, HdfsRecoveryZkTest, 
TestCSVResponseWriter, StatsReloadRaceTest, CoreAdminHandlerTest, 
TestDynamicFieldResource, RecoveryZkTest, TestConfigsApi, TestIndexSearcher, 
SolrCLIZkUtilsTest, DeleteLastCustomShardedReplicaTest, 
SolrMetricsIntegrationTest, FieldAnalysisRequestHandlerTest, 
DistributedSuggestComponentTest, TestFieldCacheWithThreads, 
PropertiesRequestHandlerTest, TestInPlaceUpdatesStandalone, 
TestStressCloudBlindAtomicUpdates, CreateCollectionCleanupTest, 
MBeansHandlerTest, TestSubQueryTransformerDistrib, 
FullSolrCloudDistribCmdsTest, TestMaxScoreQueryParser, TestSchemaResource, 
PKIAuthenticationIntegrationTest, UpdateParamsTest, OnlyLeaderIndexesTest]
   [junit4] Completed [92/699 (1!)] on J2 in 121.66s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 53667 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to