Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2094/
2 tests failed.
FAILED: org.apache.solr.cloud.ForceLeaderTest.testReplicasInLIRNoLeader
Error Message:
KeeperErrorCode = Session expired for /clusterstate.json
Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /clusterstate.json
at
__randomizedtesting.SeedInfo.seed([A47251CD2A8E11C7:42E5650D130CE8A6]:0)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:357)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:354)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:354)
at
org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:542)
at
org.apache.solr.common.cloud.ZkStateReader.forceUpdateCollection(ZkStateReader.java:310)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.updateMappingsFromZk(AbstractFullDistribZkTestBase.java:674)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.updateMappingsFromZk(AbstractFullDistribZkTestBase.java:669)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createJettys(AbstractFullDistribZkTestBase.java:464)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:334)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:991)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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)
FAILED: org.apache.solr.cloud.TestSegmentSorting.testSegmentTerminateEarly
Error Message:
responseHeader.segmentTerminatedEarly missing/false in
{zkConnected=true,segmentTerminatedEarly=false,status=0,QTime=16}
Stack Trace:
java.lang.AssertionError: responseHeader.segmentTerminatedEarly missing/false
in {zkConnected=true,segmentTerminatedEarly=false,status=0,QTime=16}
at
__randomizedtesting.SeedInfo.seed([A47251CD2A8E11C7:74D49660F6DC85AD]: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 11759 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSegmentSorting
[junit4] 2> 1326727 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/init-core-data-001
[junit4] 2> 1326744 WARN
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=60 numCloses=60
[junit4] 2> 1326744 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1326745 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1326746 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001
[junit4] 2> 1326746 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1326746 INFO (Thread-1098) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1326746 INFO (Thread-1098) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1326747 ERROR (Thread-1098) [ ] 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> 1326884 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:39356
[junit4] 2> 1326956 INFO (jetty-launcher-809-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1326956 INFO (jetty-launcher-809-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1326980 INFO (jetty-launcher-809-thread-3) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1326984 INFO (jetty-launcher-809-thread-4) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1327019 INFO (jetty-launcher-809-thread-5) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 1327067 INFO (jetty-launcher-809-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@445f6dd1{/solr,null,AVAILABLE}
[junit4] 2> 1327068 INFO (jetty-launcher-809-thread-5) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3b9d4adb{HTTP/1.1,[http/1.1]}{127.0.0.1:40014}
[junit4] 2> 1327068 INFO (jetty-launcher-809-thread-5) [ ]
o.e.j.s.Server Started @1339616ms
[junit4] 2> 1327068 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40014}
[junit4] 2> 1327068 ERROR (jetty-launcher-809-thread-5) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1327068 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1327068 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1327068 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1327081 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-09T09:49:40.545Z
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@74bccb2d{/solr,null,AVAILABLE}
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3a906203{HTTP/1.1,[http/1.1]}{127.0.0.1:42387}
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.e.j.s.Server Started @1339670ms
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42387}
[junit4] 2> 1327122 ERROR (jetty-launcher-809-thread-4) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1327122 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-09T09:49:40.599Z
[junit4] 2> 1327149 INFO (jetty-launcher-809-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@88368ad{/solr,null,AVAILABLE}
[junit4] 2> 1327149 INFO (jetty-launcher-809-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@20829b70{HTTP/1.1,[http/1.1]}{127.0.0.1:39605}
[junit4] 2> 1327149 INFO (jetty-launcher-809-thread-1) [ ]
o.e.j.s.Server Started @1339697ms
[junit4] 2> 1327149 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39605}
[junit4] 2> 1327149 ERROR (jetty-launcher-809-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1327150 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1327150 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1327150 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1327150 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-09T09:49:40.627Z
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@441f27fa{/solr,null,AVAILABLE}
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@aacdceb{HTTP/1.1,[http/1.1]}{127.0.0.1:40777}
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.e.j.s.Server Started @1339711ms
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40777}
[junit4] 2> 1327163 ERROR (jetty-launcher-809-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1327163 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-09T09:49:40.640Z
[junit4] 2> 1327179 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1327268 INFO (jetty-launcher-809-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@728a99db{/solr,null,AVAILABLE}
[junit4] 2> 1327268 INFO (jetty-launcher-809-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@690c7bbc{HTTP/1.1,[http/1.1]}{127.0.0.1:34537}
[junit4] 2> 1327268 INFO (jetty-launcher-809-thread-3) [ ]
o.e.j.s.Server Started @1339816ms
[junit4] 2> 1327268 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=34537}
[junit4] 2> 1327269 ERROR (jetty-launcher-809-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1327269 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1327269 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1327269 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 1327269 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-09T09:49:40.746Z
[junit4] 2> 1327296 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1327366 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1327399 INFO (jetty-launcher-809-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39356/solr
[junit4] 2> 1327401 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1327402 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1327416 INFO (jetty-launcher-809-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39356/solr
[junit4] 2> 1327435 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15e660b74000006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1327525 INFO (jetty-launcher-809-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39356/solr
[junit4] 2> 1327690 INFO (jetty-launcher-809-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39356/solr
[junit4] 2> 1327788 INFO (jetty-launcher-809-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39356/solr
[junit4] 2> 1328264 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1328265 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:39605_solr
[junit4] 2> 1328266 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.c.Overseer Overseer
(id=98628441225560073-127.0.0.1:39605_solr-n_0000000000) starting
[junit4] 2> 1328267 INFO (jetty-launcher-809-thread-3)
[n:127.0.0.1:34537_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1328279 INFO (jetty-launcher-809-thread-2)
[n:127.0.0.1:40777_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1328280 INFO (jetty-launcher-809-thread-2)
[n:127.0.0.1:40777_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40777_solr
[junit4] 2> 1328377 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1328424 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1328425 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1328426 INFO
(zkCallback-827-thread-1-processing-n:127.0.0.1:34537_solr)
[n:127.0.0.1:34537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1328426 INFO (jetty-launcher-809-thread-3)
[n:127.0.0.1:34537_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34537_solr
[junit4] 2> 1328427 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1328428 INFO
(zkCallback-827-thread-1-processing-n:127.0.0.1:34537_solr)
[n:127.0.0.1:34537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1328428 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40014_solr
[junit4] 2> 1328489 INFO
(zkCallback-826-thread-1-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1328506 INFO
(zkCallback-838-thread-1-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (3)
[junit4] 2> 1328519 INFO
(zkCallback-826-thread-2-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (3)
[junit4] 2> 1328527 INFO
(zkCallback-827-thread-1-processing-n:127.0.0.1:34537_solr)
[n:127.0.0.1:34537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1328527 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1328787 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39605_solr
[junit4] 2> 1328889 INFO
(zkCallback-827-thread-1-processing-n:127.0.0.1:34537_solr)
[n:127.0.0.1:34537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1328890 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1328897 INFO
(zkCallback-838-thread-1-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1328913 INFO
(zkCallback-826-thread-2-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1329325 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40014.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1329454 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40014.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1329574 INFO (jetty-launcher-809-thread-3)
[n:127.0.0.1:34537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34537.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1329972 INFO (jetty-launcher-809-thread-3)
[n:127.0.0.1:34537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34537.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1329972 INFO (jetty-launcher-809-thread-3)
[n:127.0.0.1:34537_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_34537.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1329973 INFO (jetty-launcher-809-thread-3)
[n:127.0.0.1:34537_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node3/.
[junit4] 2> 1330210 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40014.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330211 INFO (jetty-launcher-809-thread-5)
[n:127.0.0.1:40014_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node5/.
[junit4] 2> 1330228 INFO (jetty-launcher-809-thread-2)
[n:127.0.0.1:40777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40777.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330301 INFO (jetty-launcher-809-thread-2)
[n:127.0.0.1:40777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40777.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330301 INFO (jetty-launcher-809-thread-2)
[n:127.0.0.1:40777_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40777.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330302 INFO (jetty-launcher-809-thread-2)
[n:127.0.0.1:40777_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node2/.
[junit4] 2> 1330333 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39605.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330364 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39605.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330364 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39605.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330365 INFO (jetty-launcher-809-thread-1)
[n:127.0.0.1:39605_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node1/.
[junit4] 2> 1330560 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1330562 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1330575 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42387_solr
[junit4] 2> 1330576 INFO
(zkCallback-826-thread-2-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1330576 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1330603 INFO
(zkCallback-838-thread-1-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1330604 INFO
(zkCallback-827-thread-1-processing-n:127.0.0.1:34537_solr)
[n:127.0.0.1:34537_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1330668 INFO
(zkCallback-832-thread-1-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1330902 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42387.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330934 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42387.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330934 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42387.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1330935 INFO (jetty-launcher-809-thread-4)
[n:127.0.0.1:42387_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node4/.
[junit4] 2> 1334386 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 1334387 INFO
(SUITE-TestSegmentSorting-seed#[A47251CD2A8E11C7]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39356/solr ready
[junit4] 2> 1336099 INFO
(TEST-TestSegmentSorting.testSegmentTerminateEarly-seed#[A47251CD2A8E11C7]) [
] o.a.s.SolrTestCaseJ4 ###Starting testSegmentTerminateEarly
[junit4] 2> 1336149 INFO (qtp751624077-5520) [n:127.0.0.1:34537_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&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> 1336169 INFO (qtp751624077-5520) [n:127.0.0.1:34537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&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=19
[junit4] 2> 1336170 INFO (qtp751624077-5521) [n:127.0.0.1:34537_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1336171 INFO (qtp751624077-5521) [n:127.0.0.1:34537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1336185 INFO
(OverseerThreadFactory-1929-thread-1-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_solr ] o.a.s.c.CreateCollectionCmd Create collection
testSegmentTerminateEarly
[junit4] 2> 1336312 INFO
(OverseerStateUpdate-98628441225560073-127.0.0.1:39605_solr-n_0000000000)
[n:127.0.0.1:39605_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSegmentTerminateEarly",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testSegmentTerminateEarly_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42387/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1336332 INFO
(OverseerStateUpdate-98628441225560073-127.0.0.1:39605_solr-n_0000000000)
[n:127.0.0.1:39605_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSegmentTerminateEarly",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testSegmentTerminateEarly_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40777/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1336334 INFO
(OverseerStateUpdate-98628441225560073-127.0.0.1:39605_solr-n_0000000000)
[n:127.0.0.1:39605_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSegmentTerminateEarly",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testSegmentTerminateEarly_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40014/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1336345 INFO
(OverseerStateUpdate-98628441225560073-127.0.0.1:39605_solr-n_0000000000)
[n:127.0.0.1:39605_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testSegmentTerminateEarly",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testSegmentTerminateEarly_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:39605/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 1336581 INFO (qtp1150821310-5484) [n:127.0.0.1:42387_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node3&name=testSegmentTerminateEarly_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin}
status=0 QTime=0
[junit4] 2> 1336608 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node3&name=testSegmentTerminateEarly_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 1336608 INFO (qtp742521253-5505) [n:127.0.0.1:40777_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node5&name=testSegmentTerminateEarly_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin}
status=0 QTime=0
[junit4] 2> 1336609 INFO (qtp1483620124-5475) [n:127.0.0.1:40014_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node7&name=testSegmentTerminateEarly_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin}
status=0 QTime=0
[junit4] 2> 1336610 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node5&name=testSegmentTerminateEarly_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 1336610 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node7&name=testSegmentTerminateEarly_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 1336609 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node8&name=testSegmentTerminateEarly_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 1336609 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1336609 INFO (qtp1894219689-5497) [n:127.0.0.1:39605_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=class+org.apache.solr.cloud.TestSegmentSorting_configSet&newCollection=true&collection=testSegmentTerminateEarly&version=2&replicaType=NRT&async=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961&property.config=solrconfig-sortingmergepolicyfactory.xml&coreNodeName=core_node8&name=testSegmentTerminateEarly_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin}
status=0 QTime=1
[junit4] 2> 1336610 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1336611 INFO (qtp1894219689-5498) [n:127.0.0.1:39605_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961
[junit4] 2> 1336611 INFO (qtp1894219689-5498) [n:127.0.0.1:39605_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1336611 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1336619 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1336751 INFO
(zkCallback-832-thread-1-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_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> 1336751 INFO
(zkCallback-838-thread-1-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_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> 1336751 INFO
(zkCallback-826-thread-2-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_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> 1336751 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_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> 1336751 INFO
(zkCallback-826-thread-1-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_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> 1336751 INFO
(zkCallback-832-thread-2-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_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> 1336752 INFO
(zkCallback-837-thread-2-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_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> 1336752 INFO
(zkCallback-838-thread-2-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_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> 1337171 INFO (qtp751624077-5521) [n:127.0.0.1:34537_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1337172 INFO (qtp751624077-5521) [n:127.0.0.1:34537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1337612 INFO (qtp1894219689-5498) [n:127.0.0.1:39605_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961
[junit4] 2> 1337612 INFO (qtp1894219689-5498) [n:127.0.0.1:39605_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1337696 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1337697 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1337713 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1337763 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1337971 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1337981 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1338012 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1338093 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1338292 INFO (qtp751624077-5523) [n:127.0.0.1:34537_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1338324 INFO (qtp751624077-5523) [n:127.0.0.1:34537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=31
[junit4] 2> 1338621 INFO (qtp1894219689-5500) [n:127.0.0.1:39605_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961
[junit4] 2> 1338621 INFO (qtp1894219689-5500) [n:127.0.0.1:39605_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1338831 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1338916 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1338929 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1338943 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339157 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339157 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX
monitoring for
'solr_39605.solr.core.testSegmentTerminateEarly.shard2.replica_n6' (registry
'solr.core.testSegmentTerminateEarly.shard2.replica_n6') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1339157 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1339158 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.c.SolrCore
[[testSegmentTerminateEarly_shard2_replica_n6] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node1/testSegmentTerminateEarly_shard2_replica_n6],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node1/./testSegmentTerminateEarly_shard2_replica_n6/data/]
[junit4] 2> 1339176 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=48,
maxMergeAtOnceExplicit=13, maxMergedSegmentMB=48.375,
floorSegmentMB=0.806640625, forceMergeDeletesPctAllowed=4.148055790053267,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339177 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1339177 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX
monitoring for
'solr_40014.solr.core.testSegmentTerminateEarly.shard2.replica_n4' (registry
'solr.core.testSegmentTerminateEarly.shard2.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1339177 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1339178 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.c.SolrCore
[[testSegmentTerminateEarly_shard2_replica_n4] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node5/testSegmentTerminateEarly_shard2_replica_n4],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node5/./testSegmentTerminateEarly_shard2_replica_n4/data/]
[junit4] 2> 1339180 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=48,
maxMergeAtOnceExplicit=13, maxMergedSegmentMB=48.375,
floorSegmentMB=0.806640625, forceMergeDeletesPctAllowed=4.148055790053267,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339268 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1339268 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for
'solr_40777.solr.core.testSegmentTerminateEarly.shard1.replica_n2' (registry
'solr.core.testSegmentTerminateEarly.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1339268 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1339268 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.c.SolrCore
[[testSegmentTerminateEarly_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node2/testSegmentTerminateEarly_shard1_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node2/./testSegmentTerminateEarly_shard1_replica_n2/data/]
[junit4] 2> 1339270 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=48,
maxMergeAtOnceExplicit=13, maxMergedSegmentMB=48.375,
floorSegmentMB=0.806640625, forceMergeDeletesPctAllowed=4.148055790053267,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339315 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339315 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for
'solr_42387.solr.core.testSegmentTerminateEarly.shard1.replica_n1' (registry
'solr.core.testSegmentTerminateEarly.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5eb3243b
[junit4] 2> 1339316 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1339316 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.c.SolrCore
[[testSegmentTerminateEarly_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node4/testSegmentTerminateEarly_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_A47251CD2A8E11C7-001/tempDir-001/node4/./testSegmentTerminateEarly_shard1_replica_n1/data/]
[junit4] 2> 1339327 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=48,
maxMergeAtOnceExplicit=13, maxMergedSegmentMB=48.375,
floorSegmentMB=0.806640625, forceMergeDeletesPctAllowed=4.148055790053267,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339339 INFO (qtp751624077-5524) [n:127.0.0.1:34537_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1339404 INFO (qtp751624077-5524) [n:127.0.0.1:34537_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc0&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=64
[junit4] 2> 1339417 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339417 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339418 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1339418 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1339419 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=9,
maxMergeAtOnceExplicit=8, maxMergedSegmentMB=98.6025390625,
floorSegmentMB=1.2314453125, forceMergeDeletesPctAllowed=6.8760341376755285,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339419 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_solr c:testSegmentTerminateEarly s:shard2 r:core_node8
x:testSegmentTerminateEarly_shard2_replica_n6] o.a.s.s.SolrIndexSearcher
Opening [Searcher@56b56267[testSegmentTerminateEarly_shard2_replica_n6] main]
[junit4] 2> 1339441 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1339441 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1339442 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1339442 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1339443 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=9,
maxMergeAtOnceExplicit=8, maxMergedSegmentMB=98.6025390625,
floorSegmentMB=1.2314453125, forceMergeDeletesPctAllowed=6.8760341376755285,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339443 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_solr c:testSegmentTerminateEarly s:shard2 r:core_node7
x:testSegmentTerminateEarly_shard2_replica_n4] o.a.s.s.SolrIndexSearcher
Opening [Searcher@182faa1[testSegmentTerminateEarly_shard2_replica_n4] main]
[junit4] 2> 1339445 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339445 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339446 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339446 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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 1578055072924827648
[junit4] 2> 1339494 INFO
(searcherExecutor-1940-thread-1-processing-x:testSegmentTerminateEarly_shard2_replica_n6
r:core_node8 f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE
n:127.0.0.1:39605_solr s:shard2 c:testSegmentTerminateEarly)
[n:127.0.0.1:39605_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@56b56267[testSegmentTerminateEarly_shard2_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1339538 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1339580 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339580 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339581 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1339581 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1339582 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=9,
maxMergeAtOnceExplicit=8, maxMergedSegmentMB=98.6025390625,
floorSegmentMB=1.2314453125, forceMergeDeletesPctAllowed=6.8760341376755285,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339582 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_solr c:testSegmentTerminateEarly s:shard1 r:core_node3
x:testSegmentTerminateEarly_shard1_replica_n1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@32929d2b[testSegmentTerminateEarly_shard1_replica_n1] main]
[junit4] 2> 1339591 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1339592 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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> 1339592 INFO
(parallelCoreAdminExecutor-1930-thread-1-processing-n:127.0.0.1:40014_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE)
[n:127.0.0.1:40014_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 1578055073077919744
[junit4] 2> 1339600 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339601 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339601 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339605 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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 1578055073091551232
[junit4] 2> 1339605 INFO
(parallelCoreAdminExecutor-1935-thread-1-processing-n:127.0.0.1:39605_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961 CREATE)
[n:127.0.0.1:39605_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> 1339620 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1339620 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1339621 INFO
(searcherExecutor-1941-thread-1-processing-x:testSegmentTerminateEarly_shard2_replica_n4
r:core_node7 f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899089572668 CREATE
n:127.0.0.1:40014_solr s:shard2 c:testSegmentTerminateEarly)
[n:127.0.0.1:40014_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@182faa1[testSegmentTerminateEarly_shard2_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1339621 INFO
(searcherExecutor-1943-thread-1-processing-x:testSegmentTerminateEarly_shard1_replica_n1
r:core_node3 f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE
n:127.0.0.1:42387_solr s:shard1 c:testSegmentTerminateEarly)
[n:127.0.0.1:42387_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@32929d2b[testSegmentTerminateEarly_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1339621 INFO (qtp1894219689-5500) [n:127.0.0.1:39605_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961
[junit4] 2> 1339621 INFO (qtp1894219689-5500) [n:127.0.0.1:39605_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899100789961&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1339624 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 1339624 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 1339625 INFO
(parallelCoreAdminExecutor-1938-thread-1-processing-n:127.0.0.1:42387_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899066962702 CREATE)
[n:127.0.0.1:42387_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> 1339626 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.ForceMergePolicy:
ForceMergePolicy([TieredMergePolicy: maxMergeAtOnce=9,
maxMergeAtOnceExplicit=8, maxMergedSegmentMB=98.6025390625,
floorSegmentMB=1.2314453125, forceMergeDeletesPctAllowed=6.8760341376755285,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0)
[junit4] 2> 1339626 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_solr c:testSegmentTerminateEarly s:shard1 r:core_node5
x:testSegmentTerminateEarly_shard1_replica_n2] o.a.s.s.SolrIndexSearcher
Opening [Searcher@6400b3a1[testSegmentTerminateEarly_shard1_replica_n2] main]
[junit4] 2> 1339627 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1339627 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1339627 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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> 1339641 INFO
(searcherExecutor-1942-thread-1-processing-x:testSegmentTerminateEarly_shard1_replica_n2
r:core_node5 f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE
n:127.0.0.1:40777_solr s:shard1 c:testSegmentTerminateEarly)
[n:127.0.0.1:40777_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@6400b3a1[testSegmentTerminateEarly_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1339641 INFO
(parallelCoreAdminExecutor-1932-thread-1-processing-n:127.0.0.1:40777_solr
f72d6cf4-1389-4ccf-b339-c735a93fbdc05059899070053230 CREATE)
[n:127.0.0.1:40777_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 1578055073129299968
[junit4] 2> 1339641 INFO
(zkCallback-838-thread-2-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_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> 1339641 INFO
(zkCallback-838-thread-1-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_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> 1339652 INFO
(zkCallback-832-thread-2-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_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> 1339652 INFO
(zkCallback-832-thread-1-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_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> 1339652 INFO
(zkCallback-826-thread-3-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_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> 1339652 INFO
(zkCallback-837-thread-2-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_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> 1339652 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_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> 1339652 INFO
(zkCallback-826-thread-1-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_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> 1339782 INFO
(zkCallback-832-thread-1-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_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> 1339782 INFO
(zkCallback-838-thread-1-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_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> 1339782 INFO
(zkCallback-832-thread-2-processing-n:127.0.0.1:42387_solr)
[n:127.0.0.1:42387_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> 1339782 INFO
(zkCallback-838-thread-2-processing-n:127.0.0.1:40014_solr)
[n:127.0.0.1:40014_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> 1339782 INFO
(zkCallback-826-thread-1-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_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> 1339786 INFO
(zkCallback-826-thread-3-processing-n:127.0.0.1:39605_solr)
[n:127.0.0.1:39605_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> 1339786 INFO
(zkCallback-837-thread-1-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_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> 1339786 INFO
(zkCallback-837-thread-2-processing-n:127.0.0.1:40777_solr)
[n:127.0.0.1:40777_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 no
[...truncated too long message...]
unit4] 2> 1981206 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@e5a3617{/,null,UNAVAILABLE}
[junit4] 2> 1981207 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.ChaosMonkey monkey: stop jetty! 45879
[junit4] 2> 1981207 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2127750976
[junit4] 2> 1981207 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1981207 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5f75972b: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@57307393
[junit4] 2> 1981303 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1981303 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3196ae85: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@1a1a5607
[junit4] 2> 1981306 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1981306 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@42edda90: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@4e4190b7
[junit4] 2> 1981335 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1981383 INFO (coreCloseExecutor-2832-thread-1)
[n:127.0.0.1:45879_ c:collection1 s:shard2 r:core_node46
x:collection1_shard2_replica_n45] o.a.s.c.SolrCore
[collection1_shard2_replica_n45] CLOSING SolrCore
org.apache.solr.core.SolrCore@77cff000
[junit4] 2> 1981384 INFO (coreCloseExecutor-2832-thread-1)
[n:127.0.0.1:45879_ c:collection1 s:shard2 r:core_node46
x:collection1_shard2_replica_n45] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard2.replica_n45, tag=2010116096
[junit4] 2> 1981384 INFO (coreCloseExecutor-2832-thread-1)
[n:127.0.0.1:45879_ c:collection1 s:shard2 r:core_node46
x:collection1_shard2_replica_n45] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@78e77581: rootName = null,
domain = solr.core.collection1.shard2.replica_n45, service url = null, agent id
= null] for registry solr.core.collection1.shard2.replica_n45 /
com.codahale.metrics.MetricRegistry@38627d47
[junit4] 2> 1981435 INFO (coreCloseExecutor-2832-thread-1)
[n:127.0.0.1:45879_ c:collection1 s:shard2 r:core_node46
x:collection1_shard2_replica_n45] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard2.leader, tag=2010116096
[junit4] 2> 1981481 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.Overseer Overseer
(id=98628478517051407-127.0.0.1:45879_-n_0000000003) closing
[junit4] 2> 1981482 INFO
(OverseerStateUpdate-98628478517051407-127.0.0.1:45879_-n_0000000003)
[n:127.0.0.1:45879_ ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:45879_
[junit4] 2> 1981536 INFO (zkCallback-1275-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1983043 WARN
(zkCallback-1292-thread-2-processing-n:127.0.0.1:45879_) [n:127.0.0.1:45879_
] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to
ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1983088 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@76b06ea4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1983088 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7e59edff{/,null,UNAVAILABLE}
[junit4] 2> 1983089 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.ChaosMonkey monkey: stop jetty! 34996
[junit4] 2> 1983089 ERROR
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] 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> 1983156 INFO
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38284 38284
[junit4] 2> 1983315 INFO (Thread-1703) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:38284 38284
[junit4] 2> 1983316 WARN (Thread-1703) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3
/solr/collections/forceleader_last_published/state.json
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 1983317 WARN
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:45879/,
target: http://127.0.0.1:45322/
[junit4] 2> 1983317 WARN
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.SocketProxy Closing 6 connections to: http://127.0.0.1:41881/,
target: http://127.0.0.1:33452/
[junit4] 2> 1983317 WARN
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.SocketProxy Closing 3 connections to: http://127.0.0.1:34996/,
target: http://127.0.0.1:32883/
[junit4] 2> 1983317 WARN
(TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[A47251CD2A8E11C7]) [
] o.a.s.c.SocketProxy Closing 5 connections to: http://127.0.0.1:41252/,
target: http://127.0.0.1:43129/
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.ForceLeaderTest_A47251CD2A8E11C7-001
[junit4] 2> Sep 09, 2017 10:00:36 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1208, maxMBSortInHeap=7.048662514003211,
sim=RandomSimilarity(queryNorm=false): {}, locale=sl-SI, timezone=CST
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=233286544,total=525860864
[junit4] 2> NOTE: All tests run in this JVM: [TestFreeTextSuggestions,
TestExactSharedStatsCache, TestMiniSolrCloudClusterSSL,
SolrGraphiteReporterTest, CleanupOldIndexTest, ScriptEngineTest,
ChaosMonkeySafeLeaderTest, ShardRoutingCustomTest, SolrCloudExampleTest,
TolerantUpdateProcessorTest, DisMaxRequestHandlerTest,
TestMaxTokenLenTokenizer, TestScoreJoinQPNoScore, TestCustomDocTransformer,
LargeFieldTest, TestEmbeddedSolrServerConstructors, LoggingHandlerTest,
CurrencyFieldTypeTest, TestCollectionsAPIViaSolrCloudCluster,
BasicAuthIntegrationTest, ResponseHeaderTest, TestSimpleTrackingShardHandler,
HighlighterTest, SaslZkACLProviderTest, TestSolrCloudWithHadoopAuthPlugin,
DistanceFunctionTest, ConfigureRecoveryStrategyTest, TestInPlaceUpdatesDistrib,
BadCopyFieldTest, DataDrivenBlockJoinTest, TestAuthorizationFramework,
TestExpandComponent, SolrCmdDistributorTest, ResourceLoaderTest,
TestNoOpRegenerator, TestSolrConfigHandler, TestStressCloudBlindAtomicUpdates,
AnalysisAfterCoreReloadTest, OverseerModifyCollectionTest, SpatialFilterTest,
TestSolrCoreSnapshots, TestSegmentSorting, TestDistribDocBasedVersion,
ZkCLITest, OpenCloseCoreStressTest, TestDistributedGrouping,
TestLeaderInitiatedRecoveryThread, TestConfigsApi, TestCloudManagedSchema,
TestSolrIndexConfig, CoreMergeIndexesAdminHandlerTest,
CdcrReplicationHandlerTest, DistribCursorPagingTest,
DistributedMLTComponentTest, TestSolrCloudWithDelegationTokens,
DistributedFacetPivotLongTailTest, MetricsConfigTest, TestMaxScoreQueryParser,
MissingSegmentRecoveryTest, TestOrdValues, TestTrackingShardHandlerFactory,
TestDFISimilarityFactory, TestXIncludeConfig, ForceLeaderTest]
[junit4] Completed [204/732 (2!)] on J1 in 131.19s, 3 tests, 1 error, 1
skipped <<< FAILURES!
[...truncated 47825 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
