lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_144) - Build # 20445 - Still Unstable!
Date Sun, 10 Sep 2017 09:55:37 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20445/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseSerialGC

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

Error Message:
responseHeader.segmentTerminatedEarly missing/false in {zkConnected=true,segmentTerminatedEarly=false,status=0,QTime=6}

Stack Trace:
java.lang.AssertionError: responseHeader.segmentTerminatedEarly missing/false in {zkConnected=true,segmentTerminatedEarly=false,status=0,QTime=6}
	at __randomizedtesting.SeedInfo.seed([F2306300E8AEB816:2296A4AD34FC2C7C]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.cloud.SegmentTerminateEarlyTestState.queryTimestampDescendingSegmentTerminateEarlyYes(SegmentTerminateEarlyTestState.java:150)
	at org.apache.solr.cloud.TestSegmentSorting.testSegmentTerminateEarly(TestSegmentSorting.java:109)
	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$8.evaluate(RandomizedRunner.java:934)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
	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 13000 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSegmentSorting
   [junit4]   2> 2464179 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-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/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/init-core-data-001
   [junit4]   2> 2464180 WARN  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 2464180 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2464181 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 2464182 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001
   [junit4]   2> 2464182 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2464182 INFO  (Thread-2759) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2464182 INFO  (Thread-2759) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2464183 ERROR (Thread-2759) [    ] 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> 2464282 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:37759
   [junit4]   2> 2464287 INFO  (jetty-launcher-1307-thread-1) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2464287 INFO  (jetty-launcher-1307-thread-3) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2464287 INFO  (jetty-launcher-1307-thread-5) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2464287 INFO  (jetty-launcher-1307-thread-2) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2464287 INFO  (jetty-launcher-1307-thread-4) [    ] o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 2464288 INFO  (jetty-launcher-1307-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d681141{/solr,null,AVAILABLE}
   [junit4]   2> 2464289 INFO  (jetty-launcher-1307-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15fef936{/solr,null,AVAILABLE}
   [junit4]   2> 2464289 INFO  (jetty-launcher-1307-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@57402f8d{SSL,[ssl, http/1.1]}{127.0.0.1:36821}
   [junit4]   2> 2464289 INFO  (jetty-launcher-1307-thread-2) [    ] o.e.j.s.Server Started @2465897ms
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36821}
   [junit4]   2> 2464290 ERROR (jetty-launcher-1307-thread-2) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2fe9f37b{/solr,null,AVAILABLE}
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-09-10T09:33:09.656Z
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@78423282{SSL,[ssl, http/1.1]}{127.0.0.1:44025}
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-3) [    ] o.e.j.s.Server Started @2465898ms
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44025}
   [junit4]   2> 2464290 ERROR (jetty-launcher-1307-thread-3) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 2464290 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2464291 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 2464291 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-09-10T09:33:09.657Z
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@264a0d26{SSL,[ssl, http/1.1]}{127.0.0.1:46805}
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.e.j.s.Server Started @2465902ms
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46805}
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-5) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@151c6630{/solr,null,AVAILABLE}
   [junit4]   2> 2464295 ERROR (jetty-launcher-1307-thread-4) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-09-10T09:33:09.661Z
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-5) [    ] o.e.j.s.AbstractConnector Started ServerConnector@2cf106ef{SSL,[ssl, http/1.1]}{127.0.0.1:37951}
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-5) [    ] o.e.j.s.Server Started @2465903ms
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37951}
   [junit4]   2> 2464296 ERROR (jetty-launcher-1307-thread-5) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2464296 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 2464296 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2464296 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 2464296 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-09-10T09:33:09.662Z
   [junit4]   2> 2464295 INFO  (jetty-launcher-1307-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@26fcb611{/solr,null,AVAILABLE}
   [junit4]   2> 2464297 INFO  (jetty-launcher-1307-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@61ed1988{SSL,[ssl, http/1.1]}{127.0.0.1:40923}
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-1) [    ] o.e.j.s.Server Started @2465904ms
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40923}
   [junit4]   2> 2464301 ERROR (jetty-launcher-1307-thread-1) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 8.0.0
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config dir: null
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-09-10T09:33:09.667Z
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2464301 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2464304 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2464304 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2464328 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2464334 INFO  (jetty-launcher-1307-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37759/solr
   [junit4]   2> 2464334 INFO  (jetty-launcher-1307-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37759/solr
   [junit4]   2> 2464341 INFO  (jetty-launcher-1307-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37759/solr
   [junit4]   2> 2464341 INFO  (jetty-launcher-1307-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37759/solr
   [junit4]   2> 2464350 INFO  (jetty-launcher-1307-thread-5) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37759/solr
   [junit4]   2> 2464395 INFO  (jetty-launcher-1307-thread-3) [n:127.0.0.1:44025_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2464395 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2464395 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36821_solr
   [junit4]   2> 2464396 INFO  (jetty-launcher-1307-thread-3) [n:127.0.0.1:44025_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44025_solr
   [junit4]   2> 2464396 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.c.Overseer Overseer (id=98634038611083274-127.0.0.1:36821_solr-n_0000000000) starting
   [junit4]   2> 2464396 INFO  (jetty-launcher-1307-thread-4) [n:127.0.0.1:46805_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2464397 INFO  (zkCallback-1328-thread-1-processing-n:127.0.0.1:36821_solr) [n:127.0.0.1:36821_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2464397 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2464398 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2464398 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2464399 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2464399 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40923_solr
   [junit4]   2> 2464400 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2464401 INFO  (jetty-launcher-1307-thread-4) [n:127.0.0.1:46805_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46805_solr
   [junit4]   2> 2464401 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36821_solr
   [junit4]   2> 2464401 INFO  (OverseerStateUpdate-98634038611083274-127.0.0.1:36821_solr-n_0000000000) [n:127.0.0.1:36821_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2464401 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2464401 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2464401 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2464401 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2464402 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2464402 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2464402 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 2464420 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2464421 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37951_solr
   [junit4]   2> 2464421 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2464421 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2464421 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2464442 INFO  (zkCallback-1334-thread-1-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2464442 INFO  (zkCallback-1328-thread-2-processing-n:127.0.0.1:36821_solr) [n:127.0.0.1:36821_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (5)
   [junit4]   2> 2464477 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40923.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464479 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37951.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464480 INFO  (jetty-launcher-1307-thread-4) [n:127.0.0.1:46805_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46805.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464481 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36821.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464512 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40923.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464512 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40923.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464513 INFO  (jetty-launcher-1307-thread-1) [n:127.0.0.1:40923_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node1/.
   [junit4]   2> 2464514 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37951.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464514 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37951.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464515 INFO  (jetty-launcher-1307-thread-4) [n:127.0.0.1:46805_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46805.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464515 INFO  (jetty-launcher-1307-thread-4) [n:127.0.0.1:46805_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46805.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464515 INFO  (jetty-launcher-1307-thread-5) [n:127.0.0.1:37951_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node5/.
   [junit4]   2> 2464515 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36821.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464515 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36821.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464516 INFO  (jetty-launcher-1307-thread-4) [n:127.0.0.1:46805_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node4/.
   [junit4]   2> 2464516 INFO  (jetty-launcher-1307-thread-2) [n:127.0.0.1:36821_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node2/.
   [junit4]   2> 2464563 INFO  (jetty-launcher-1307-thread-3) [n:127.0.0.1:44025_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464571 INFO  (jetty-launcher-1307-thread-3) [n:127.0.0.1:44025_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464571 INFO  (jetty-launcher-1307-thread-3) [n:127.0.0.1:44025_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2464572 INFO  (jetty-launcher-1307-thread-3) [n:127.0.0.1:44025_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node3/.
   [junit4]   2> 2464665 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 2464665 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37759/solr ready
   [junit4]   2> 2464818 INFO  (TEST-TestSegmentSorting.testSegmentTerminateEarly-seed#[F2306300E8AEB816]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testSegmentTerminateEarly
   [junit4]   2> 2464837 INFO  (qtp1037806427-15476) [n:127.0.0.1:44025_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 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> 2464838 INFO  (OverseerThreadFactory-4642-thread-1-processing-n:127.0.0.1:36821_solr) [n:127.0.0.1:36821_solr    ] o.a.s.c.CreateCollectionCmd Create collection testSegmentTerminateEarly
   [junit4]   2> 2464941 INFO  (OverseerStateUpdate-98634038611083274-127.0.0.1:36821_solr-n_0000000000) [n:127.0.0.1:36821_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":"https://127.0.0.1:44025/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2464941 INFO  (OverseerStateUpdate-98634038611083274-127.0.0.1:36821_solr-n_0000000000) [n:127.0.0.1:36821_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":"https://127.0.0.1:40923/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2464942 INFO  (OverseerStateUpdate-98634038611083274-127.0.0.1:36821_solr-n_0000000000) [n:127.0.0.1:36821_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":"https://127.0.0.1:46805/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2464943 INFO  (OverseerStateUpdate-98634038611083274-127.0.0.1:36821_solr-n_0000000000) [n:127.0.0.1:36821_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":"https://127.0.0.1:37951/solr",
   [junit4]   2>   "type":"NRT"} 
   [junit4]   2> 2465152 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node3&name=testSegmentTerminateEarly_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
   [junit4]   2> 2465152 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2465172 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node5&name=testSegmentTerminateEarly_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin
   [junit4]   2> 2465173 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2465175 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node8&name=testSegmentTerminateEarly_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin
   [junit4]   2> 2465175 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2465178 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node7&name=testSegmentTerminateEarly_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin
   [junit4]   2> 2465179 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2465282 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2465282 INFO  (zkCallback-1334-thread-1-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2465282 INFO  (zkCallback-1330-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2465282 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2465282 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2465282 INFO  (zkCallback-1329-thread-2-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2465282 INFO  (zkCallback-1327-thread-2-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2465282 INFO  (zkCallback-1334-thread-2-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2466161 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466168 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466192 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466192 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466199 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466201 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466201 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466218 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466292 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466303 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466304 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.core.testSegmentTerminateEarly.shard1.replica_n1' (registry 'solr.core.testSegmentTerminateEarly.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2466304 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2466304 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node3/testSegmentTerminateEarly_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node3/./testSegmentTerminateEarly_shard1_replica_n1/data/]
   [junit4]   2> 2466313 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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([AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1315741585, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1])
   [junit4]   2> 2466314 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466315 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466322 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466323 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466324 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466324 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37951.solr.core.testSegmentTerminateEarly.shard2.replica_n6' (registry 'solr.core.testSegmentTerminateEarly.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2466324 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2466324 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node5/testSegmentTerminateEarly_shard2_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node5/./testSegmentTerminateEarly_shard2_replica_n6/data/]
   [junit4]   2> 2466324 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40923.solr.core.testSegmentTerminateEarly.shard1.replica_n2' (registry 'solr.core.testSegmentTerminateEarly.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2466324 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2466324 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node1/testSegmentTerminateEarly_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node1/./testSegmentTerminateEarly_shard1_replica_n2/data/]
   [junit4]   2> 2466326 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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([AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1315741585, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1])
   [junit4]   2> 2466327 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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([AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1315741585, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1])
   [junit4]   2> 2466334 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466334 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46805.solr.core.testSegmentTerminateEarly.shard2.replica_n4' (registry 'solr.core.testSegmentTerminateEarly.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@593ffded
   [junit4]   2> 2466334 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2466334 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node4/testSegmentTerminateEarly_shard2_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001/tempDir-001/node4/./testSegmentTerminateEarly_shard2_replica_n4/data/]
   [junit4]   2> 2466336 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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([AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1315741585, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1])
   [junit4]   2> 2466358 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466358 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466359 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2466359 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2466359 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38477895535109163])
   [junit4]   2> 2466359 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@295fb72e[testSegmentTerminateEarly_shard1_replica_n1] main]
   [junit4]   2> 2466360 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466360 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466361 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2466361 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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 1578144620061130752
   [junit4]   2> 2466362 INFO  (searcherExecutor-4653-thread-1-processing-n:127.0.0.1:44025_solr x:testSegmentTerminateEarly_shard1_replica_n1 s:shard1 c:testSegmentTerminateEarly r:core_node3) [n:127.0.0.1:44025_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@295fb72e[testSegmentTerminateEarly_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2466364 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_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> 2466365 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466365 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466366 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2466366 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2466367 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38477895535109163])
   [junit4]   2> 2466367 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c4d5259[testSegmentTerminateEarly_shard2_replica_n6] main]
   [junit4]   2> 2466368 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466368 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466368 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466368 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466369 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2466369 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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 1578144620069519360
   [junit4]   2> 2466369 INFO  (searcherExecutor-4654-thread-1-processing-n:127.0.0.1:37951_solr x:testSegmentTerminateEarly_shard2_replica_n6 s:shard2 c:testSegmentTerminateEarly r:core_node8) [n:127.0.0.1:37951_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@6c4d5259[testSegmentTerminateEarly_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2466369 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2466369 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2466370 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38477895535109163])
   [junit4]   2> 2466370 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@35706e3f[testSegmentTerminateEarly_shard1_replica_n2] main]
   [junit4]   2> 2466371 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466371 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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> 2466372 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2466372 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_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 1578144620072665088
   [junit4]   2> 2466372 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_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> 2466372 INFO  (searcherExecutor-4655-thread-1-processing-n:127.0.0.1:40923_solr x:testSegmentTerminateEarly_shard1_replica_n2 s:shard1 c:testSegmentTerminateEarly r:core_node5) [n:127.0.0.1:40923_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@35706e3f[testSegmentTerminateEarly_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2466379 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466379 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466379 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2466380 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2466380 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38477895535109163])
   [junit4]   2> 2466380 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ca7f4f3[testSegmentTerminateEarly_shard2_replica_n4] main]
   [junit4]   2> 2466381 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466381 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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> 2466382 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2466382 INFO  (searcherExecutor-4657-thread-1-processing-n:127.0.0.1:46805_solr x:testSegmentTerminateEarly_shard2_replica_n4 s:shard2 c:testSegmentTerminateEarly r:core_node7) [n:127.0.0.1:46805_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@3ca7f4f3[testSegmentTerminateEarly_shard2_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2466382 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_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 1578144620083150848
   [junit4]   2> 2466474 INFO  (zkCallback-1327-thread-2-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2466474 INFO  (zkCallback-1329-thread-2-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2466474 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2466474 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2466474 INFO  (zkCallback-1330-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2466474 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2466474 INFO  (zkCallback-1334-thread-1-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2466474 INFO  (zkCallback-1334-thread-2-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2466864 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2466864 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2466864 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44025/solr/testSegmentTerminateEarly_shard1_replica_n1/
   [junit4]   2> 2466864 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=testSegmentTerminateEarly_shard1_replica_n1 url=https://127.0.0.1:44025/solr START replicas=[https://127.0.0.1:40923/solr/testSegmentTerminateEarly_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 2466867 INFO  (qtp227878181-15478) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.S.Request [testSegmentTerminateEarly_shard1_replica_n2]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2466873 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2466873 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2466873 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/
   [junit4]   2> 2466873 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=testSegmentTerminateEarly_shard2_replica_n6 url=https://127.0.0.1:37951/solr START replicas=[https://127.0.0.1:46805/solr/testSegmentTerminateEarly_shard2_replica_n4/] nUpdates=100
   [junit4]   2> 2466876 INFO  (qtp343081603-15492) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.S.Request [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2467165 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=testSegmentTerminateEarly_shard1_replica_n1 url=https://127.0.0.1:44025/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 2467165 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 2467165 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 2467165 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 2467166 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44025/solr/testSegmentTerminateEarly_shard1_replica_n1/ shard1
   [junit4]   2> 2467173 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=testSegmentTerminateEarly_shard2_replica_n6 url=https://127.0.0.1:37951/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 2467173 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 2467173 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 2467173 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 2467175 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/ shard2
   [junit4]   2> 2467276 INFO  (zkCallback-1334-thread-2-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2467276 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2467276 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2467276 INFO  (zkCallback-1334-thread-1-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2467276 INFO  (zkCallback-1329-thread-2-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2467276 INFO  (zkCallback-1327-thread-2-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2467276 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2467276 INFO  (zkCallback-1330-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2467317 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2467318 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] 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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node3&name=testSegmentTerminateEarly_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=2165
   [junit4]   2> 2467325 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2467326 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] 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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node8&name=testSegmentTerminateEarly_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=2150
   [junit4]   2> 2467375 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] 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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node5&name=testSegmentTerminateEarly_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=2202
   [junit4]   2> 2467385 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] 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&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node7&name=testSegmentTerminateEarly_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=2207
   [junit4]   2> 2467387 INFO  (qtp1037806427-15476) [n:127.0.0.1:44025_solr    ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
   [junit4]   2> 2467487 INFO  (zkCallback-1334-thread-2-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2467487 INFO  (zkCallback-1329-thread-2-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2467487 INFO  (zkCallback-1334-thread-1-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_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> 2467487 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_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> 2467487 INFO  (zkCallback-1327-thread-2-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2467487 INFO  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_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> 2467487 INFO  (zkCallback-1330-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2467487 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_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> 2468387 INFO  (qtp1037806427-15476) [n:127.0.0.1:44025_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={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=3550
   [junit4]   2> 2468387 INFO  (TEST-TestSegmentSorting.testSegmentTerminateEarly-seed#[F2306300E8AEB816]) [    ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: testSegmentTerminateEarly failOnTimeout:true timeout (sec):330
   [junit4]   1> -
   [junit4]   1> replica:core_node3 rstate:active live:true
   [junit4]   1> replica:core_node5 rstate:active live:true
   [junit4]   1> replica:core_node7 rstate:active live:true
   [junit4]   1> replica:core_node8 rstate:active live:true
   [junit4]   1> no one is recoverying
   [junit4]   2> 2468388 INFO  (TEST-TestSegmentSorting.testSegmentTerminateEarly-seed#[F2306300E8AEB816]) [    ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: testSegmentTerminateEarly
   [junit4]   2> 2468395 INFO  (qtp227878181-15509) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n2]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:44025/solr/testSegmentTerminateEarly_shard1_replica_n1/&wt=javabin&version=2}{add=[1 (1578144622189740032)]} 0 1
   [junit4]   2> 2468395 INFO  (qtp1037806427-15503) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1578144622189740032)]} 0 4
   [junit4]   2> 2468403 INFO  (qtp343081603-15511) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/&wt=javabin&version=2}{add=[2 (1578144622200225792)]} 0 1
   [junit4]   2> 2468404 INFO  (qtp189172749-15510) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n6]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1578144622200225792)]} 0 2
   [junit4]   2> 2468404 INFO  (qtp343081603-15482) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/&wt=javabin&version=2}{add=[3 (1578144622203371520)]} 0 0
   [junit4]   2> 2468405 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n6]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1578144622203371520)]} 0 0
   [junit4]   2> 2468405 INFO  (qtp227878181-15504) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n2]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:44025/solr/testSegmentTerminateEarly_shard1_replica_n1/&wt=javabin&version=2}{add=[4 (1578144622204420096)]} 0 0
   [junit4]   2> 2468406 INFO  (qtp1037806427-15480) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1578144622204420096)]} 0 0
   [junit4]   2> 2468406 INFO  (qtp343081603-15492) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/&wt=javabin&version=2}{add=[5 (1578144622205468672)]} 0 0
   [junit4]   2> 2468407 INFO  (qtp189172749-15479) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n6]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1578144622205468672)]} 0 0
   [junit4]   2> 2468407 INFO  (qtp343081603-15513) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/&wt=javabin&version=2}{add=[6 (1578144622206517248)]} 0 0
   [junit4]   2> 2468408 INFO  (qtp189172749-15508) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n6]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1578144622206517248)]} 0 0
   [junit4]   2> 2468408 INFO  (qtp343081603-15511) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/&wt=javabin&version=2}{add=[7 (1578144622207565824)]} 0 0
   [junit4]   2> 2468409 INFO  (qtp189172749-15510) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n6]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1578144622207565824)]} 0 0
   [junit4]   2> 2468409 INFO  (qtp227878181-15478) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n2]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:44025/solr/testSegmentTerminateEarly_shard1_replica_n1/&wt=javabin&version=2}{add=[8 (1578144622208614400)]} 0 0
   [junit4]   2> 2468410 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[8 (1578144622208614400)]} 0 0
   [junit4]   2> 2468410 INFO  (qtp343081603-15511) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n4]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:37951/solr/testSegmentTerminateEarly_shard2_replica_n6/&wt=javabin&version=2}{add=[9 (1578144622209662976)]} 0 0
   [junit4]   2> 2468411 INFO  (qtp189172749-15475) [n:127.0.0.1:37951_solr c:testSegmentTerminateEarly s:shard2 r:core_node8 x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard2_replica_n6]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1578144622209662976)]} 0 0
   [junit4]   2> 2468412 INFO  (qtp227878181-15474) [n:127.0.0.1:40923_solr c:testSegmentTerminateEarly s:shard1 r:core_node5 x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n2]  webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:44025/solr/testSegmentTerminateEarly_shard1_replica_n1/&wt=javabin&version=2}{add=[10 (1578144622210711552)]} 0 0
   [junit4]   2> 2468412 INFO  (qtp1037806427-15476) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [testSegmentTerminateEarly_shard1_replica_n1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[10 (1578144622210711552)]} 0 0
   [junit4]   2> 2468416 INFO  (qtp343081603-15492) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1578144622215954432,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2468416 INFO  (qtp343081603-15492) [n:127.0.0.1:46805_solr c:testSegmentTerminateEarly s:shard2 r:core_node7 x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@69ffef4a commitCommandVersion:1578144622215954432
   [junit4]   2> 2468416 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shard1 r:core_node3 x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1578144622215954432,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2468416 INFO  (qtp1037806427-15516) [n:127.0.0.1:44025_solr c:testSegmentTerminateEarly s:shar

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

junit4]   2> 2486100 INFO  (jetty-closer-1308-thread-5) [    ] o.a.s.c.Overseer Overseer (id=98634038611083274-127.0.0.1:36821_solr-n_0000000000) closing
   [junit4]   2> 2486100 INFO  (zkCallback-1328-thread-1-processing-n:127.0.0.1:36821_solr) [n:127.0.0.1:36821_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
   [junit4]   2> 2486100 INFO  (OverseerStateUpdate-98634038611083274-127.0.0.1:36821_solr-n_0000000000) [n:127.0.0.1:36821_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:36821_solr
   [junit4]   2> 2486100 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
   [junit4]   2> 2486100 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (3)
   [junit4]   2> 2486100 INFO  (zkCallback-1327-thread-2-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 2486100 INFO  (zkCallback-1328-thread-2-processing-n:127.0.0.1:36821_solr) [n:127.0.0.1:36821_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
   [junit4]   2> 2486101 INFO  (zkCallback-1330-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 2486101 INFO  (zkCallback-1329-thread-1-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 2486101 INFO  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44025_solr
   [junit4]   2> 2486101 INFO  (zkCallback-1330-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 2486105 WARN  (zkCallback-1330-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2486105 INFO  (jetty-closer-1308-thread-4) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2fe9f37b{/solr,null,UNAVAILABLE}
   [junit4]   2> 2487599 WARN  (zkCallback-1334-thread-1-processing-n:127.0.0.1:37951_solr) [n:127.0.0.1:37951_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2487600 INFO  (jetty-closer-1308-thread-3) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@151c6630{/solr,null,UNAVAILABLE}
   [junit4]   2> 2487600 WARN  (zkCallback-1327-thread-1-processing-n:127.0.0.1:40923_solr) [n:127.0.0.1:40923_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2487600 INFO  (jetty-closer-1308-thread-1) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@26fcb611{/solr,null,UNAVAILABLE}
   [junit4]   2> 2487601 WARN  (zkCallback-1329-thread-2-processing-n:127.0.0.1:46805_solr) [n:127.0.0.1:46805_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2487601 INFO  (jetty-closer-1308-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@15fef936{/solr,null,UNAVAILABLE}
   [junit4]   2> 2487601 WARN  (zkCallback-1328-thread-2-processing-n:127.0.0.1:36821_solr) [n:127.0.0.1:36821_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2487601 INFO  (jetty-closer-1308-thread-5) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6d681141{/solr,null,UNAVAILABLE}
   [junit4]   2> 2487602 ERROR (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] 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> 2487602 INFO  (SUITE-TestSegmentSorting-seed#[F2306300E8AEB816]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37759 37759
   [junit4]   2> 2495725 INFO  (Thread-2759) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37759 37759
   [junit4]   2> 2495726 WARN  (Thread-2759) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/aliases.json
   [junit4]   2> 	8	/solr/configs/class org.apache.solr.cloud.TestSegmentSorting_configSet
   [junit4]   2> 	5	/solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	32	/solr/collections/testAtomicUpdateOfSegmentSortField/state.json
   [junit4]   2> 	24	/solr/collections/testSegmentTerminateEarly/state.json
   [junit4]   2> 	8	/solr/clusterprops.json
   [junit4]   2> 	8	/solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	12	/solr/collections
   [junit4]   2> 	8	/solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSegmentSorting_F2306300E8AEB816-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), multiDefault=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Direct), range_facet_l=DocValuesFormat(name=Lucene70), odd_l1=DocValuesFormat(name=Asserting), quad_l1=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene70), id=DocValuesFormat(name=Memory), intDvoDefault=DocValuesFormat(name=Memory), timestamp_i_dvo=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1723, maxMBSortInHeap=5.305893044052851, sim=RandomSimilarity(queryNorm=false): {}, locale=zh-TW, timezone=America/Antigua
   [junit4]   2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=8,threads=1,free=270212432,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [RequestHandlersTest, TestJoin, TestLRUStatsCache, ReplicationFactorTest, TestLMDirichletSimilarityFactory, IndexBasedSpellCheckerTest, TestLegacyNumericRangeQueryBuilder, TestFaceting, TestSolrDeletionPolicy1, SegmentsInfoRequestHandlerTest, TestCSVResponseWriter, SpellCheckCollatorTest, TestManagedSynonymGraphFilterFactory, TestWordDelimiterFilterFactory, TestRandomFlRTGCloud, RAMDirectoryFactoryTest, TestQuerySenderNoQuery, TestSimpleQParserPlugin, TestConfigSetImmutable, SolrGangliaReporterTest, TestSolrQueryParser, TestSimpleTextCodec, DistributedSpellCheckComponentTest, TestNamedUpdateProcessors, SolrRequestParserTest, SynonymTokenizerTest, TestPerFieldSimilarityWithDefaultOverride, TestFieldTypeResource, TestCopyFieldCollectionResource, DistanceUnitsTest, PluginInfoTest, CustomCollectionTest, V2ApiIntegrationTest, TestRestoreCore, TestNumericRangeQuery64, TestBinaryField, SolrMetricManagerTest, CdcrUpdateLogTest, InfixSuggestersTest, DocValuesMissingTest, TestLegacyTerms, SpellCheckCollatorWithCollapseTest, DeleteStatusTest, TestUseDocValuesAsStored2, HdfsTlogReplayBufferedWhileIndexingTest, CoreAdminRequestStatusTest, SolrPluginUtilsTest, ExternalFileFieldSortTest, TestStressLucene, TestMacros, TestCollectionAPIs, CursorPagingTest, TestFastOutputStream, UpdateParamsTest, TestDistributedSearch, TestWriterPerf, TestSchemaManager, TestSchemaSimilarityResource, TestConfig, TestPHPSerializedResponseWriter, TestConfigSetsAPIExclusivity, SpatialHeatmapFacetsTest, TestGraphTermsQParserPlugin, TestPayloadCheckQParserPlugin, StressHdfsTest, CollectionReloadTest, TestRandomFaceting, TestPushWriter, TestCollationFieldDocValues, QueryResultKeyTest, CollectionsAPIAsyncDistributedZkTest, TestCSVLoader, ZkNodePropsTest, TestDocSet, DebugComponentTest, DistributedDebugComponentTest, TestUninvertingReader, TestIntervalFaceting, TestJsonRequest, TestDistributedStatsComponentCardinality, TestMergePolicyConfig, TestManagedResourceStorage, TestGeoJSONResponseWriter, OutputWriterTest, ZkStateWriterTest, TestFunctionQuery, TestSolrCloudWithSecureImpersonation, DocValuesNotIndexedTest, TestIndexingPerformance, TestFieldCollectionResource, SolrShardReporterTest, JvmMetricsTest, TestOmitPositions, TestCloudJSONFacetJoinDomain, TestNRTOpen, SolrCoreTest, UninvertDocValuesMergePolicyTest, LeaderElectionIntegrationTest, DistributedFacetPivotSmallTest, FullHLLTest, TestCollapseQParserPlugin, RankQueryTest, TestClassicSimilarityFactory, TestFieldCacheSortRandom, TestCollationField, TestSSLRandomization, FieldAnalysisRequestHandlerTest, TestRTimerTree, CursorMarkTest, CdcrReplicationDistributedZkTest, TestCrossCoreJoin, HdfsNNFailoverTest, ChangedSchemaMergeTest, BasicDistributedZkTest, BlockCacheTest, HighlighterConfigTest, TestComponentsName, TestRandomDVFaceting, BlockJoinFacetSimpleTest, TestCloudRecovery, TestCollectionAPI, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudClusterSSL, TestPullReplicaErrorHandling, TestRebalanceLeaders, TestRequestForwarding, TestSegmentSorting]
   [junit4] Completed [550/732 (1!)] on J0 in 31.56s, 2 tests, 1 failure <<< FAILURES!

[...truncated 46788 lines...]

Mime
View raw message