Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2145/

1 tests failed.
FAILED:  org.apache.solr.cloud.TestSegmentSorting.testSegmentTerminateEarly

Error Message:
KeeperErrorCode = Session expired for /clusterstate.json

Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = 
Session expired for /clusterstate.json
        at 
__randomizedtesting.SeedInfo.seed([BB4B15E709F42CB:DB1276F3ACCDD6A1]:0)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
        at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1102)
        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:308)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:308)
        at 
org.apache.solr.common.cloud.ZkStateReader.createClusterStateWatchersAndUpdate(ZkStateReader.java:428)
        at 
org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:442)
        at 
org.apache.solr.cloud.TestSegmentSorting.ensureClusterEmpty(TestSegmentSorting.java:63)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
        at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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 
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.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13591 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSegmentSorting
   [junit4]   2> 5628343 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/init-core-data-001
   [junit4]   2> 5628376 WARN  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
   [junit4]   2> 5628376 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 5628377 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 5628378 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001
   [junit4]   2> 5628378 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 5628421 INFO  (Thread-5168) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 5628421 INFO  (Thread-5168) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 5628510 ERROR (Thread-5168) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 5628559 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:43802
   [junit4]   2> 5628643 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 5628643 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 5628644 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 5628644 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 5628678 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2edc59d2{/solr,null,AVAILABLE}
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@31bda700{HTTP/1.1,[http/1.1]}{127.0.0.1:37896}
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.e.j.s.Server Started @5641143ms
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37896}
   [junit4]   2> 5628679 ERROR (jetty-launcher-3874-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 5628679 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 5628680 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-01T23:46:48.831Z
   [junit4]   2> 5628681 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@457d0d82{/solr,null,AVAILABLE}
   [junit4]   2> 5628705 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6bddb53a{/solr,null,AVAILABLE}
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5ae3d11d{HTTP/1.1,[http/1.1]}{127.0.0.1:34588}
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.e.j.s.Server Started @5641169ms
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34588}
   [junit4]   2> 5628706 ERROR (jetty-launcher-3874-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 5628706 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-01T23:46:48.857Z
   [junit4]   2> 5628787 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2e1e4bcc{/solr,null,AVAILABLE}
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@49c1d310{HTTP/1.1,[http/1.1]}{127.0.0.1:41011}
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.e.j.s.Server Started @5641251ms
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41011}
   [junit4]   2> 5628788 ERROR (jetty-launcher-3874-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 5628788 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-01T23:46:48.939Z
   [junit4]   2> 5628805 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 5628856 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2fd75cc6{/solr,null,AVAILABLE}
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4f5eca7f{HTTP/1.1,[http/1.1]}{127.0.0.1:35083}
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.e.j.s.Server Started @5641320ms
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35083}
   [junit4]   2> 5628857 ERROR (jetty-launcher-3874-thread-5) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 5628857 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-01T23:46:49.008Z
   [junit4]   2> 5628937 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@708a2708{HTTP/1.1,[http/1.1]}{127.0.0.1:33736}
   [junit4]   2> 5628937 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.e.j.s.Server Started @5641401ms
   [junit4]   2> 5628937 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=33736}
   [junit4]   2> 5628938 ERROR (jetty-launcher-3874-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 5628938 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 5628938 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 5628938 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 5628938 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-01T23:46:49.089Z
   [junit4]   2> 5628939 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 5629064 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 5629084 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 5629088 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 5629198 INFO  (jetty-launcher-3874-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43802/solr
   [junit4]   2> 5629308 INFO  (jetty-launcher-3874-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43802/solr
   [junit4]   2> 5629344 INFO  (jetty-launcher-3874-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43802/solr
   [junit4]   2> 5629402 INFO  (jetty-launcher-3874-thread-5) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43802/solr
   [junit4]   2> 5629454 INFO  (jetty-launcher-3874-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43802/solr
   [junit4]   2> 5629681 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15f79faeca00009, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 5629768 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15f79faeca0000a, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 5629768 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15f79faeca00008, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 5630048 INFO  (jetty-launcher-3874-thread-5) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 5630048 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 5630051 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:37896_solr
   [junit4]   2> 5630051 INFO  (jetty-launcher-3874-thread-5) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35083_solr
   [junit4]   2> 5630084 INFO  (jetty-launcher-3874-thread-2) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 5630100 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.Overseer Overseer 
(id=98931835444133901-127.0.0.1:37896_solr-n_0000000000) starting
   [junit4]   2> 5630157 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 5630158 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 5630158 INFO  
(zkCallback-3902-thread-1-processing-n:127.0.0.1:35083_solr) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 5630159 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 5630159 INFO  (jetty-launcher-3874-thread-2) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:33736_solr
   [junit4]   2> 5630174 INFO  
(zkCallback-3902-thread-1-processing-n:127.0.0.1:35083_solr) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 5630407 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 5630414 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 5630414 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 5630588 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 5630825 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34588_solr
   [junit4]   2> 5630896 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 5630909 INFO  (jetty-launcher-3874-thread-2) 
[n:127.0.0.1:33736_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33736.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5630934 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 5631040 INFO  
(zkCallback-3899-thread-1-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 5631093 INFO  
(zkCallback-3902-thread-1-processing-n:127.0.0.1:35083_solr) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 5631093 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 5631093 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 5631094 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 5631094 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41011_solr
   [junit4]   2> 5631095 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 5631095 INFO  
(zkCallback-3902-thread-1-processing-n:127.0.0.1:35083_solr) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 5631113 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 5631113 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 5631113 INFO  
(zkCallback-3899-thread-1-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 5631211 INFO  (jetty-launcher-3874-thread-2) 
[n:127.0.0.1:33736_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33736.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631211 INFO  (jetty-launcher-3874-thread-2) 
[n:127.0.0.1:33736_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33736.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631212 INFO  (jetty-launcher-3874-thread-2) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node2/.
   [junit4]   2> 5631223 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37896_solr
   [junit4]   2> 5631224 INFO  
(zkCallback-3902-thread-1-processing-n:127.0.0.1:35083_solr) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 5631224 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 5631224 INFO  
(zkCallback-3899-thread-1-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 5631235 INFO  (jetty-launcher-3874-thread-5) 
[n:127.0.0.1:35083_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35083.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631348 INFO  (jetty-launcher-3874-thread-5) 
[n:127.0.0.1:35083_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35083.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631348 INFO  (jetty-launcher-3874-thread-5) 
[n:127.0.0.1:35083_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35083.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631349 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 5631366 INFO  (jetty-launcher-3874-thread-5) 
[n:127.0.0.1:35083_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node5/.
   [junit4]   2> 5631422 INFO  
(OverseerStateUpdate-98931835444133901-127.0.0.1:37896_solr-n_0000000000) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (4) -> (5)
   [junit4]   2> 5631624 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34588.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631676 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34588.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631676 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_34588.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631678 INFO  (jetty-launcher-3874-thread-1) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node1/.
   [junit4]   2> 5631749 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37896.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631770 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41011.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631806 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41011.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631806 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41011.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631807 INFO  (jetty-launcher-3874-thread-4) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node4/.
   [junit4]   2> 5631823 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37896.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631823 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37896.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5631878 INFO  (jetty-launcher-3874-thread-3) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node3/.
   [junit4]   2> 5633341 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 5633342 INFO  
(SUITE-TestSegmentSorting-seed#[BB4B15E709F42CB]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43802/solr ready
   [junit4]   2> 5636262 INFO  
(TEST-TestSegmentSorting.testSegmentTerminateEarly-seed#[BB4B15E709F42CB]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testSegmentTerminateEarly
   [junit4]   2> 5636307 INFO  (qtp1535893367-21457) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
async=be61b0d5-b680-4f81-bfe4-84d14a17117a&replicationFactor=2&property.config=solrconfig-sortingmergepolicyfactory.xml&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&name=testSegmentTerminateEarly&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5636326 INFO  (qtp1535893367-21457) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=be61b0d5-b680-4f81-bfe4-84d14a17117a&replicationFactor=2&property.config=solrconfig-sortingmergepolicyfactory.xml&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&name=testSegmentTerminateEarly&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=18
   [junit4]   2> 5636350 INFO  (qtp1535893367-21458) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5636362 INFO  
(OverseerThreadFactory-6716-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
testSegmentTerminateEarly
   [junit4]   2> 5636496 INFO  (qtp1535893367-21458) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=146
   [junit4]   2> 5637318 INFO  
(OverseerStateUpdate-98931835444133901-127.0.0.1:37896_solr-n_0000000000) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testSegmentTerminateEarly",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testSegmentTerminateEarly_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:34588/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 5637356 INFO  
(OverseerStateUpdate-98931835444133901-127.0.0.1:37896_solr-n_0000000000) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testSegmentTerminateEarly",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"testSegmentTerminateEarly_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33736/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 5637358 INFO  
(OverseerStateUpdate-98931835444133901-127.0.0.1:37896_solr-n_0000000000) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testSegmentTerminateEarly",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"testSegmentTerminateEarly_shard2_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:37896/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 5637372 INFO  
(OverseerStateUpdate-98931835444133901-127.0.0.1:37896_solr-n_0000000000) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"testSegmentTerminateEarly",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"testSegmentTerminateEarly_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:41011/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 5637522 INFO  (qtp1535893367-21459) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5637558 INFO  (qtp1535893367-21459) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=35
   [junit4]   2> 5637644 INFO  (qtp1535893367-21455) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node3&name=testSegmentTerminateEarly_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 5637644 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node3&name=testSegmentTerminateEarly_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
   [junit4]   2> 5637644 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 5637790 INFO  (qtp687951000-21465) [n:127.0.0.1:37896_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node7&name=testSegmentTerminateEarly_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 5637791 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node7&name=testSegmentTerminateEarly_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin
   [junit4]   2> 5637791 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 5637791 INFO  (qtp2051209292-21488) [n:127.0.0.1:41011_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node8&name=testSegmentTerminateEarly_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin}
 status=0 QTime=1
   [junit4]   2> 5637792 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node8&name=testSegmentTerminateEarly_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin
   [junit4]   2> 5637792 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 5637792 INFO  (qtp297229159-21476) [n:127.0.0.1:33736_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node5&name=testSegmentTerminateEarly_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 5637793 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr    ] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node5&name=testSegmentTerminateEarly_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin
   [junit4]   2> 5637793 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 5637793 INFO  (qtp1535893367-21461) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143
   [junit4]   2> 5637793 INFO  (qtp1535893367-21461) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5638010 INFO  
(zkCallback-3899-thread-1-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638010 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638017 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638018 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638029 INFO  
(zkCallback-3900-thread-2-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638042 INFO  
(zkCallback-3885-thread-2-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638046 INFO  
(zkCallback-3901-thread-2-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638070 INFO  
(zkCallback-3899-thread-2-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5638591 INFO  (qtp1535893367-21460) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5638595 INFO  (qtp1535893367-21460) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=20
   [junit4]   2> 5638771 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 5638939 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143
   [junit4]   2> 5638939 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5638939 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.s.IndexSchema 
[testSegmentTerminateEarly_shard1_replica_n1] Schema name=test
   [junit4]   2> 5638982 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 5639225 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.s.IndexSchema 
[testSegmentTerminateEarly_shard2_replica_n6] Schema name=test
   [junit4]   2> 5639555 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 5639624 INFO  (qtp1535893367-21457) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5639625 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 5639939 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143
   [junit4]   2> 5639940 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5639942 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.s.IndexSchema 
[testSegmentTerminateEarly_shard2_replica_n4] Schema name=test
   [junit4]   2> 5639953 INFO  (qtp1535893367-21457) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=329
   [junit4]   2> 5640017 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.s.IndexSchema 
[testSegmentTerminateEarly_shard1_replica_n2] Schema name=test
   [junit4]   2> 5640907 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.s.IndexSchema Loaded 
schema test/1.0 with uniqueid field id
   [junit4]   2> 5640941 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143
   [junit4]   2> 5640941 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5640956 INFO  (qtp1535893367-21459) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5640957 INFO  (qtp1535893367-21459) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5640959 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.CoreContainer Creating 
SolrCore 'testSegmentTerminateEarly_shard2_replica_n6' using configuration from 
collection testSegmentTerminateEarly, trusted=true
   [junit4]   2> 5640960 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_41011.solr.core.testSegmentTerminateEarly.shard2.replica_n6' (registry 
'solr.core.testSegmentTerminateEarly.shard2.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5640960 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 5640960 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrCore 
[[testSegmentTerminateEarly_shard2_replica_n6] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node4/testSegmentTerminateEarly_shard2_replica_n6],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node4/./testSegmentTerminateEarly_shard2_replica_n6/data/]
   [junit4]   2> 5640974 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6754180152912086])
   [junit4]   2> 5641235 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.s.IndexSchema Loaded 
schema test/1.0 with uniqueid field id
   [junit4]   2> 5641780 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'testSegmentTerminateEarly_shard1_replica_n1' using configuration from 
collection testSegmentTerminateEarly, trusted=true
   [junit4]   2> 5641800 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_34588.solr.core.testSegmentTerminateEarly.shard1.replica_n1' (registry 
'solr.core.testSegmentTerminateEarly.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5641800 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 5641800 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrCore 
[[testSegmentTerminateEarly_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node1/testSegmentTerminateEarly_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node1/./testSegmentTerminateEarly_shard1_replica_n1/data/]
   [junit4]   2> 5641820 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.s.IndexSchema Loaded 
schema test/1.0 with uniqueid field id
   [junit4]   2> 5641826 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 5641827 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 5641827 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 5641828 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 5641829 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=32, 
maxMergeAtOnceExplicit=20, maxMergedSegmentMB=84.2705078125, 
floorSegmentMB=1.3984375, forceMergeDeletesPctAllowed=3.8205300798560216, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.48571855381479734)
   [junit4]   2> 5641829 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@5cda2e5c[testSegmentTerminateEarly_shard2_replica_n6] main]
   [junit4]   2> 5641843 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5641843 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5641844 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 5641844 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1582909381143429120
   [junit4]   2> 5641846 INFO  
(searcherExecutor-6725-thread-1-processing-x:testSegmentTerminateEarly_shard2_replica_n6
 r:core_node8 be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE 
n:127.0.0.1:41011_solr s:shard2 c:testSegmentTerminateEarly) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrCore 
[testSegmentTerminateEarly_shard2_replica_n6] Registered new searcher 
Searcher@5cda2e5c[testSegmentTerminateEarly_shard2_replica_n6] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 5641949 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143
   [junit4]   2> 5641949 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5641953 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6754180152912086])
   [junit4]   2> 5641965 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.CoreContainer Creating 
SolrCore 'testSegmentTerminateEarly_shard1_replica_n2' using configuration from 
collection testSegmentTerminateEarly, trusted=true
   [junit4]   2> 5641973 INFO  (qtp1535893367-21460) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5641985 INFO  (qtp1535893367-21460) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=12
   [junit4]   2> 5642017 INFO  
(parallelCoreAdminExecutor-6721-thread-1-processing-n:127.0.0.1:41011_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326800927023 CREATE) 
[n:127.0.0.1:41011_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 
x:testSegmentTerminateEarly_shard2_replica_n6] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 5642019 INFO  
(zkCallback-3899-thread-2-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642019 INFO  
(zkCallback-3899-thread-1-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642019 INFO  
(zkCallback-3901-thread-2-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642019 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642021 INFO  
(zkCallback-3900-thread-2-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642021 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642034 INFO  
(zkCallback-3885-thread-2-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642034 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642076 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_33736.solr.core.testSegmentTerminateEarly.shard1.replica_n2' (registry 
'solr.core.testSegmentTerminateEarly.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5642076 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 5642076 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrCore 
[[testSegmentTerminateEarly_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node2/testSegmentTerminateEarly_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node2/./testSegmentTerminateEarly_shard1_replica_n2/data/]
   [junit4]   2> 5642078 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6754180152912086])
   [junit4]   2> 5642143 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.s.IndexSchema Loaded 
schema test/1.0 with uniqueid field id
   [junit4]   2> 5642203 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.CoreContainer Creating 
SolrCore 'testSegmentTerminateEarly_shard2_replica_n4' using configuration from 
collection testSegmentTerminateEarly, trusted=true
   [junit4]   2> 5642204 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 
'solr_37896.solr.core.testSegmentTerminateEarly.shard2.replica_n4' (registry 
'solr.core.testSegmentTerminateEarly.shard2.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7255456f
   [junit4]   2> 5642204 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 5642216 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrCore 
[[testSegmentTerminateEarly_shard2_replica_n4] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node3/testSegmentTerminateEarly_shard2_replica_n4],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_BB4B15E709F42CB-001/tempDir-001/node3/./testSegmentTerminateEarly_shard2_replica_n4/data/]
   [junit4]   2> 5642218 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6754180152912086])
   [junit4]   2> 5642472 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 5642473 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 5642473 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 5642474 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 5642474 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=32, 
maxMergeAtOnceExplicit=20, maxMergedSegmentMB=84.2705078125, 
floorSegmentMB=1.3984375, forceMergeDeletesPctAllowed=3.8205300798560216, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.48571855381479734)
   [junit4]   2> 5642475 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@420ad416[testSegmentTerminateEarly_shard1_replica_n1] main]
   [junit4]   2> 5642475 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5642476 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5642509 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 5642526 INFO  
(searcherExecutor-6727-thread-1-processing-x:testSegmentTerminateEarly_shard1_replica_n1
 r:core_node3 be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE 
n:127.0.0.1:34588_solr s:shard1 c:testSegmentTerminateEarly) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrCore 
[testSegmentTerminateEarly_shard1_replica_n1] Registered new searcher 
Searcher@420ad416[testSegmentTerminateEarly_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 5642526 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1582909381858557952
   [junit4]   2> 5642548 INFO  
(parallelCoreAdminExecutor-6719-thread-1-processing-n:127.0.0.1:34588_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143 CREATE) 
[n:127.0.0.1:34588_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 
x:testSegmentTerminateEarly_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 5642550 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 5642550 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 5642551 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 5642551 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 5642552 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=32, 
maxMergeAtOnceExplicit=20, maxMergedSegmentMB=84.2705078125, 
floorSegmentMB=1.3984375, forceMergeDeletesPctAllowed=3.8205300798560216, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.48571855381479734)
   [junit4]   2> 5642552 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@69f47f5a[testSegmentTerminateEarly_shard1_replica_n2] main]
   [junit4]   2> 5642553 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5642553 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5642553 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 5642553 INFO  
(parallelCoreAdminExecutor-6712-thread-1-processing-n:127.0.0.1:33736_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1582909381886869504
   [junit4]   2> 5642599 INFO  
(searcherExecutor-6731-thread-1-processing-x:testSegmentTerminateEarly_shard1_replica_n2
 r:core_node5 be61b0d5-b680-4f81-bfe4-84d14a17117a9689326785683065 CREATE 
n:127.0.0.1:33736_solr s:shard1 c:testSegmentTerminateEarly) 
[n:127.0.0.1:33736_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrCore 
[testSegmentTerminateEarly_shard1_replica_n2] Registered new searcher 
Searcher@69f47f5a[testSegmentTerminateEarly_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 5642656 INFO  
(zkCallback-3885-thread-1-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642656 INFO  
(zkCallback-3885-thread-2-processing-n:127.0.0.1:34588_solr) 
[n:127.0.0.1:34588_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642656 INFO  
(zkCallback-3899-thread-2-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642656 INFO  
(zkCallback-3899-thread-1-processing-n:127.0.0.1:41011_solr) 
[n:127.0.0.1:41011_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642657 INFO  
(zkCallback-3901-thread-1-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642657 INFO  
(zkCallback-3901-thread-2-processing-n:127.0.0.1:37896_solr) 
[n:127.0.0.1:37896_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642659 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 5642659 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 5642660 INFO  
(zkCallback-3900-thread-2-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642660 INFO  
(zkCallback-3900-thread-1-processing-n:127.0.0.1:33736_solr) 
[n:127.0.0.1:33736_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/testSegmentTerminateEarly/state.json] for collection 
[testSegmentTerminateEarly] has occurred - updating... (live nodes size: [5])
   [junit4]   2> 5642692 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 5642692 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 5642693 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy: 
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=32, 
maxMergeAtOnceExplicit=20, maxMergedSegmentMB=84.2705078125, 
floorSegmentMB=1.3984375, forceMergeDeletesPctAllowed=3.8205300798560216, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.48571855381479734)
   [junit4]   2> 5642694 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@78a7d35e[testSegmentTerminateEarly_shard2_replica_n4] main]
   [junit4]   2> 5642694 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5642695 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/class 
org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 5642695 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.h.ReplicationHandler 
Commits will be reserved for 10000ms.
   [junit4]   2> 5642696 INFO  
(searcherExecutor-6732-thread-1-processing-x:testSegmentTerminateEarly_shard2_replica_n4
 r:core_node7 be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE 
n:127.0.0.1:37896_solr s:shard2 c:testSegmentTerminateEarly) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrCore 
[testSegmentTerminateEarly_shard2_replica_n4] Registered new searcher 
Searcher@78a7d35e[testSegmentTerminateEarly_shard2_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 5642696 INFO  
(parallelCoreAdminExecutor-6720-thread-1-processing-n:127.0.0.1:37896_solr 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326787189974 CREATE) 
[n:127.0.0.1:37896_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1582909382036815872
   [junit4]   2> 5642953 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143
   [junit4]   2> 5642953 INFO  (qtp1535893367-21454) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a9689326694910143&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 5642989 INFO  (qtp1535893367-21455) [n:127.0.0.1:34588_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 5643002 INFO  (qtp1535893367-21455) [n:127.0.0.1:34588_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=be61b0d5-b680-4f81-bfe4-84d14a17117a&action=REQUESTSTATUS&wt=javabin&version=2}
 

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

domSet' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 10. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/response/SmileResponseWriter.java
 (at line 33)
 [ecj-lint]     new SmileWriter(out, request, response).writeResponse();
 [ecj-lint]     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 11. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/schema/OpenExchangeRatesOrgProvider.java
 (at line 146)
 [ecj-lint]     ratesJsonStream = 
resourceLoader.openResource(ratesFileLocation);
 [ecj-lint]     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'ratesJsonStream' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 12. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/search/CollapsingQParserPlugin.java
 (at line 1315)
 [ecj-lint]     UninvertingReader uninvertingReader = new UninvertingReader(new 
ReaderWrapper(searcher.getSlowAtomicReader(), collapseField), mapping);
 [ecj-lint]                       ^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'uninvertingReader' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 13. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/search/DocSlice.java
 (at line 145)
 [ecj-lint]     HashDocSet h = new HashDocSet(docs,offset,len);
 [ecj-lint]                ^
 [ecj-lint] Resource leak: 'h' is never closed
 [ecj-lint] ----------
 [ecj-lint] 14. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/search/DocSlice.java
 (at line 154)
 [ecj-lint]     HashDocSet h = new HashDocSet(docs,offset,len);
 [ecj-lint]                ^
 [ecj-lint] Resource leak: 'h' is never closed
 [ecj-lint] ----------
 [ecj-lint] 15. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/search/DocSlice.java
 (at line 163)
 [ecj-lint]     HashDocSet h = new HashDocSet(docs,offset,len);
 [ecj-lint]                ^
 [ecj-lint] Resource leak: 'h' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 16. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/search/function/OrdFieldSource.java
 (at line 79)
 [ecj-lint]     SolrIndexSearcher is = (SolrIndexSearcher) o;
 [ecj-lint]                       ^^
 [ecj-lint] Resource leak: 'is' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 17. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/search/function/ReverseOrdFieldSource.java
 (at line 79)
 [ecj-lint]     SolrIndexSearcher is = (SolrIndexSearcher) o;
 [ecj-lint]                       ^^
 [ecj-lint] Resource leak: 'is' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 18. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/servlet/SolrDispatchFilter.java
 (at line 291)
 [ecj-lint]     throw new SolrException(ErrorCode.SERVER_ERROR, "Error occurred 
while loading solr.xml from zookeeper", e);
 [ecj-lint]     
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Resource leak: 'loader' is not closed at this location
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 19. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/CdcrTransactionLog.java
 (at line 98)
 [ecj-lint]     ChannelFastInputStream is = new ChannelFastInputStream(channel, 
pos);
 [ecj-lint]                            ^^
 [ecj-lint] Resource leak: 'is' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 20. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java
 (at line 191)
 [ecj-lint]     LogCodec codec = new LogCodec(resolver);
 [ecj-lint]              ^^^^^
 [ecj-lint] Resource leak: 'codec' is never closed
 [ecj-lint] ----------
 [ecj-lint] 21. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java
 (at line 261)
 [ecj-lint]     LogCodec codec = new LogCodec(resolver);
 [ecj-lint]              ^^^^^
 [ecj-lint] Resource leak: 'codec' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 22. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/TransactionLog.java
 (at line 228)
 [ecj-lint]     ChannelFastInputStream is = new ChannelFastInputStream(channel, 
pos);
 [ecj-lint]                            ^^
 [ecj-lint] Resource leak: 'is' is never closed
 [ecj-lint] ----------
 [ecj-lint] 23. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/TransactionLog.java
 (at line 228)
 [ecj-lint]     ChannelFastInputStream is = new ChannelFastInputStream(channel, 
pos);
 [ecj-lint]                            ^^
 [ecj-lint] Resource leak: 'is' is never closed
 [ecj-lint] ----------
 [ecj-lint] 24. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/TransactionLog.java
 (at line 260)
 [ecj-lint]     LogCodec codec = new LogCodec(resolver);
 [ecj-lint]              ^^^^^
 [ecj-lint] Resource leak: 'codec' is never closed
 [ecj-lint] ----------
 [ecj-lint] 25. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/TransactionLog.java
 (at line 275)
 [ecj-lint]     LogCodec codec = new LogCodec(resolver);
 [ecj-lint]              ^^^^^
 [ecj-lint] Resource leak: 'codec' is never closed
 [ecj-lint] ----------
 [ecj-lint] 26. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/TransactionLog.java
 (at line 529)
 [ecj-lint]     LogCodec codec = new LogCodec(resolver);
 [ecj-lint]              ^^^^^
 [ecj-lint] Resource leak: 'codec' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 27. ERROR in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/processor/DistributedUpdateProcessor.java
 (at line 372)
 [ecj-lint]     /** For {@link CollectionParams.CollectionAction#SPLITSHARD} */
 [ecj-lint]                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Javadoc: Invalid member type qualification
 [ecj-lint] ----------
 [ecj-lint] 28. ERROR in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/processor/DistributedUpdateProcessor.java
 (at line 381)
 [ecj-lint]     /** For {@link CollectionParams.CollectionAction#SPLITSHARD} */
 [ecj-lint]                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Javadoc: Invalid member type qualification
 [ecj-lint] ----------
 [ecj-lint] 29. ERROR in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/processor/DistributedUpdateProcessor.java
 (at line 406)
 [ecj-lint]     /** For {@link CollectionParams.CollectionAction#SPLITSHARD} */
 [ecj-lint]                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Javadoc: Invalid member type qualification
 [ecj-lint] ----------
 [ecj-lint] 30. ERROR in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/update/processor/DistributedUpdateProcessor.java
 (at line 432)
 [ecj-lint]     /** For {@link CollectionParams.CollectionAction#MIGRATE} */
 [ecj-lint]                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
 [ecj-lint] Javadoc: Invalid member type qualification
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 31. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/util/SimplePostTool.java
 (at line 655)
 [ecj-lint]     BAOS bos =  new BAOS();
 [ecj-lint]          ^^^
 [ecj-lint] Resource leak: 'bos' is never closed
 [ecj-lint] ----------
 [ecj-lint] 32. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/util/SimplePostTool.java
 (at line 655)
 [ecj-lint]     BAOS bos =  new BAOS();
 [ecj-lint]          ^^^
 [ecj-lint] Resource leak: 'bos' is never closed
 [ecj-lint] ----------
 [ecj-lint] ----------
 [ecj-lint] 33. WARNING in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/java/org/apache/solr/util/SolrLogLayout.java
 (at line 149)
 [ecj-lint]     SolrQueryRequest req = requestInfo == null ? null : 
requestInfo.getReq();
 [ecj-lint]                      ^^^
 [ecj-lint] Resource leak: 'req' is never closed
 [ecj-lint] ----------
 [ecj-lint] 33 problems (4 errors, 29 warnings)

BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:826: 
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:101: 
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:684:
 The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:2087:
 The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:2126:
 Compile failed; see the compiler error output for details.

Total time: 278 minutes 47 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to