Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6748/
Java: 64bit/jdk-9-ea+173 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.ShardSplitTest.testSplitWithChaosMonkey
Error Message:
There are still nodes recoverying - waited for 330 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330
seconds
at
__randomizedtesting.SeedInfo.seed([293125BE5DA80AC6:A216F66F1CAEA142]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:183)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:140)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:135)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:907)
at
org.apache.solr.cloud.ShardSplitTest.testSplitWithChaosMonkey(ShardSplitTest.java:436)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 11568 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\init-core-data-001
[junit4] 2> 503128 INFO
(SUITE-ShardSplitTest-seed#[293125BE5DA80AC6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 503128 INFO
(SUITE-ShardSplitTest-seed#[293125BE5DA80AC6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-5776";)
[junit4] 2> 503128 INFO
(SUITE-ShardSplitTest-seed#[293125BE5DA80AC6]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 503148 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 503149 INFO (Thread-1837) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 503149 INFO (Thread-1837) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 503157 ERROR (Thread-1837) [ ] 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> 503250 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkTestServer start zk server on port:51082
[junit4] 2> 503293 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 0x15d463e15d70000, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 503381 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 503387 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 503390 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 503396 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 503402 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 503405 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 503410 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 503413 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 503416 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 503431 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 503463 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractZkTestCase put
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 503467 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 0x15d463e15d70001, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 503468 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly
asked otherwise
[junit4] 2> 504229 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 504230 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2ec56163{/,null,AVAILABLE}
[junit4] 2> 504234 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@72d9b547{HTTP/1.1,[http/1.1]}{127.0.0.1:51089}
[junit4] 2> 504234 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server Started @505615ms
[junit4] 2> 504234 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\tempDir-001/control/data,
hostPort=51089,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\control-001\cores,
replicaType=NRT}
[junit4] 2> 504234 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 504234 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 504235 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 504235 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 504235 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-15T12:34:21.328740Z
[junit4] 2> 504240 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 0x15d463e15d70002, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 504240 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 504240 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\control-001\solr.xml
[junit4] 2> 504243 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 504251 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51082/solr
[junit4] 2> 504255 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 0x15d463e15d70003, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 504855 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 504857 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51089_
[junit4] 2> 504864 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=98311999340347396-127.0.0.1:51089_-n_0000000000)
starting
[junit4] 2> 504874 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51089_
[junit4] 2> 504876 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 504992 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 504998 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 504998 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 505001 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\control-001\cores
[junit4] 2> 505059 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 505067 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51082/solr ready
[junit4] 2> 505079 INFO (qtp1887831414-6193) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:51089_&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 505122 INFO (OverseerThreadFactory-3178-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 505250 INFO (qtp1887831414-6195) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 505255 INFO (qtp1887831414-6195) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 505396 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 506286 INFO (qtp1887831414-6195) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 506301 INFO (qtp1887831414-6195) [ ] o.a.s.s.IndexSchema
[control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 506365 INFO (qtp1887831414-6195) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 506403 INFO (qtp1887831414-6195) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'control_collection_shard1_replica_n1' using configuration
from collection control_collection, trusted=true
[junit4] 2> 506404 INFO (qtp1887831414-6195) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 506404 INFO (qtp1887831414-6195) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 506404 INFO (qtp1887831414-6195) [ ] o.a.s.c.SolrCore
[[control_collection_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\control-001\cores\control_collection_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 506415 INFO (qtp1887831414-6195) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=90.5986328125,
floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=29.909096492685613,
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 506452 WARN (qtp1887831414-6195) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 506564 INFO (qtp1887831414-6195) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 506564 INFO (qtp1887831414-6195) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 506566 INFO (qtp1887831414-6195) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 506566 INFO (qtp1887831414-6195) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 506569 INFO (qtp1887831414-6195) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=87.9814453125,
floorSegmentMB=0.9130859375, forceMergeDeletesPctAllowed=28.713299927934685,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.17693655700016617
[junit4] 2> 506570 INFO (qtp1887831414-6195) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@72d6098e[control_collection_shard1_replica_n1] main]
[junit4] 2> 506580 INFO (qtp1887831414-6195) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 506583 INFO (qtp1887831414-6195) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 506587 INFO (qtp1887831414-6195) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 506587 INFO (qtp1887831414-6195) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1572991993045319680
[junit4] 2> 506590 INFO (searcherExecutor-3181-thread-1) [ ]
o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher
Searcher@72d6098e[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 506607 INFO (qtp1887831414-6195) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 506607 INFO (qtp1887831414-6195) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 506607 INFO (qtp1887831414-6195) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:51089/control_collection_shard1_replica_n1/
[junit4] 2> 506607 INFO (qtp1887831414-6195) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 506607 INFO (qtp1887831414-6195) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:51089/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 506607 INFO (qtp1887831414-6195) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 506625 INFO (qtp1887831414-6195) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51089/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 506735 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 506782 INFO (qtp1887831414-6195) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 506798 INFO (qtp1887831414-6195) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1547
[junit4] 2> 506833 INFO (qtp1887831414-6193) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 506903 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 507142 INFO
(OverseerCollectionConfigSetProcessor-98311999340347396-127.0.0.1:51089_-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 507839 INFO (qtp1887831414-6193) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:51089_&wt=javabin&version=2}
status=0 QTime=2759
[junit4] 2> 507866 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 507873 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51082/solr ready
[junit4] 2> 507873 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 507874 INFO (qtp1887831414-6198) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 507884 INFO (OverseerThreadFactory-3178-thread-2) [ ]
o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 507885 WARN (OverseerThreadFactory-3178-thread-2) [ ]
o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1)
without cores.
[junit4] 2> 508105 INFO (qtp1887831414-6198) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 508105 INFO (qtp1887831414-6198) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2}
status=0 QTime=230
[junit4] 2> 508646 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-1-001
of type TLOG
[junit4] 2> 508647 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 508648 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@8bec6fe{/,null,AVAILABLE}
[junit4] 2> 508649 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4dda8659{HTTP/1.1,[http/1.1]}{127.0.0.1:51110}
[junit4] 2> 508649 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server Started @510030ms
[junit4] 2> 508649 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\tempDir-001/jetty1,
hostPort=51110,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-1-001\cores,
replicaType=TLOG}
[junit4] 2> 508649 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 508650 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 508650 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 508650 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 508650 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-15T12:34:25.743608300Z
[junit4] 2> 508656 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 508656 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-1-001\solr.xml
[junit4] 2> 508661 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 508666 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51082/solr
[junit4] 2> 508695 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 508697 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 508700 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51110_
[junit4] 2> 508750 INFO (zkCallback-991-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 508750 INFO (zkCallback-986-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 508750 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 508814 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 508825 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 508825 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 508827 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-1-001\cores
[junit4] 2> 508888 INFO (qtp1541802432-6232) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:51110_&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 508904 INFO
(OverseerCollectionConfigSetProcessor-98311999340347396-127.0.0.1:51089_-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 508920 INFO (OverseerThreadFactory-3178-thread-3) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:51110_ for creating new replica
[junit4] 2> 508936 INFO (qtp1541802432-6235) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_t0&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 508936 INFO (qtp1541802432-6235) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 509957 INFO (qtp1541802432-6235) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 509979 INFO (qtp1541802432-6235) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_t0] Schema name=test
[junit4] 2> 510081 INFO (qtp1541802432-6235) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 510230 INFO (qtp1541802432-6235) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_t0' using configuration from
collection collection1, trusted=true
[junit4] 2> 510231 INFO (qtp1541802432-6235) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_t0' (registry
'solr.core.collection1.shard2.replica_t0') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 510231 INFO (qtp1541802432-6235) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 510231 INFO (qtp1541802432-6235) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_t0] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-1-001\cores\collection1_shard2_replica_t0],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-1-001\cores\collection1_shard2_replica_t0\data\]
[junit4] 2> 510258 INFO (qtp1541802432-6235) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=90.5986328125,
floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=29.909096492685613,
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 510267 WARN (qtp1541802432-6235) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 510405 INFO (qtp1541802432-6235) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 510405 INFO (qtp1541802432-6235) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 510406 INFO (qtp1541802432-6235) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 510407 INFO (qtp1541802432-6235) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 510409 INFO (qtp1541802432-6235) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=87.9814453125,
floorSegmentMB=0.9130859375, forceMergeDeletesPctAllowed=28.713299927934685,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.17693655700016617
[junit4] 2> 510409 INFO (qtp1541802432-6235) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@425cc3a6[collection1_shard2_replica_t0] main]
[junit4] 2> 510416 INFO (qtp1541802432-6235) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 510418 INFO (qtp1541802432-6235) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 510419 INFO (qtp1541802432-6235) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 510420 INFO (qtp1541802432-6235) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1572991997064511488
[junit4] 2> 510421 INFO (searcherExecutor-3192-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_t0] Registered new searcher
Searcher@425cc3a6[collection1_shard2_replica_t0]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 510432 INFO (qtp1541802432-6235) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 510432 INFO (qtp1541802432-6235) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 510433 INFO (qtp1541802432-6235) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:51110/collection1_shard2_replica_t0/
[junit4] 2> 510433 INFO (qtp1541802432-6235) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 510433 INFO (qtp1541802432-6235) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:51110/collection1_shard2_replica_t0/ has no replicas
[junit4] 2> 510433 INFO (qtp1541802432-6235) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 510434 INFO (qtp1541802432-6235) [ ] o.a.s.c.ZkController
collection1_shard2_replica_t0 stopping background replication from leader
[junit4] 2> 510441 INFO (qtp1541802432-6235) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51110/collection1_shard2_replica_t0/ shard2
[junit4] 2> 510598 INFO (qtp1541802432-6235) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 510605 INFO (qtp1541802432-6235) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_t0&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=1666
[junit4] 2> 510614 INFO (qtp1541802432-6232) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:51110_&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2}
status=0 QTime=1726
[junit4] 2> 510922 INFO
(OverseerCollectionConfigSetProcessor-98311999340347396-127.0.0.1:51089_-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 511459 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-2-001
of type TLOG
[junit4] 2> 511460 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 511460 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5e02edcc{/,null,AVAILABLE}
[junit4] 2> 511462 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@61a6bffb{HTTP/1.1,[http/1.1]}{127.0.0.1:51124}
[junit4] 2> 511463 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server Started @512844ms
[junit4] 2> 511463 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\tempDir-001/jetty2,
hostPort=51124,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-2-001\cores,
replicaType=TLOG}
[junit4] 2> 511464 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 511464 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 511464 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 511464 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 511464 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-15T12:34:28.557947600Z
[junit4] 2> 511474 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 511474 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-2-001\solr.xml
[junit4] 2> 511480 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 511485 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51082/solr
[junit4] 2> 511493 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 0x15d463e15d7000b, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 511533 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 511536 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 511539 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51124_
[junit4] 2> 511542 INFO (zkCallback-997-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 511544 INFO (zkCallback-991-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 511544 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 511545 INFO (zkCallback-986-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 511641 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 511648 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 511649 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 511651 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-2-001\cores
[junit4] 2> 511683 INFO (qtp1541802432-6236) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:51124_&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 511687 INFO (OverseerThreadFactory-3178-thread-4) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:51124_ for creating new replica
[junit4] 2> 511689 INFO (qtp243838020-6263) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t0&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 511690 INFO (qtp243838020-6263) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 512705 INFO (qtp243838020-6263) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 512716 INFO (qtp243838020-6263) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_t0] Schema name=test
[junit4] 2> 512765 INFO (qtp243838020-6263) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 512784 INFO (qtp243838020-6263) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t0' using configuration from
collection collection1, trusted=true
[junit4] 2> 512785 INFO (qtp243838020-6263) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_t0' (registry
'solr.core.collection1.shard1.replica_t0') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 512785 INFO (qtp243838020-6263) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 512785 INFO (qtp243838020-6263) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_t0] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-2-001\cores\collection1_shard1_replica_t0],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-2-001\cores\collection1_shard1_replica_t0\data\]
[junit4] 2> 512794 INFO (qtp243838020-6263) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=90.5986328125,
floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=29.909096492685613,
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 512800 WARN (qtp243838020-6263) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 512884 INFO (qtp243838020-6263) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 512884 INFO (qtp243838020-6263) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 512884 INFO (qtp243838020-6263) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 512884 INFO (qtp243838020-6263) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 512884 INFO (qtp243838020-6263) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=87.9814453125,
floorSegmentMB=0.9130859375, forceMergeDeletesPctAllowed=28.713299927934685,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.17693655700016617
[junit4] 2> 512891 INFO (qtp243838020-6263) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@19a4b4d1[collection1_shard1_replica_t0] main]
[junit4] 2> 512909 INFO (qtp243838020-6263) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 512915 INFO (qtp243838020-6263) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 512916 INFO (qtp243838020-6263) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 512916 INFO (searcherExecutor-3203-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_t0] Registered new searcher
Searcher@19a4b4d1[collection1_shard1_replica_t0]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 512918 INFO (qtp243838020-6263) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1572991999683854336
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:51124/collection1_shard1_replica_t0/
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:51124/collection1_shard1_replica_t0/ has no replicas
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 512959 INFO (qtp243838020-6263) [ ] o.a.s.c.ZkController
collection1_shard1_replica_t0 stopping background replication from leader
[junit4] 2> 512971 INFO (qtp243838020-6263) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51124/collection1_shard1_replica_t0/ shard1
[junit4] 2> 513135 INFO (qtp243838020-6263) [ ] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 513135 INFO (qtp243838020-6263) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t0&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=1447
[junit4] 2> 513140 INFO (qtp1541802432-6236) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:51124_&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
status=0 QTime=1457
[junit4] 2> 513696 INFO
(OverseerCollectionConfigSetProcessor-98311999340347396-127.0.0.1:51089_-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 513977 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-3-001
of type TLOG
[junit4] 2> 513979 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 513980 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4606b894{/,null,AVAILABLE}
[junit4] 2> 513981 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6c2651df{HTTP/1.1,[http/1.1]}{127.0.0.1:51137}
[junit4] 2> 513982 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server Started @515363ms
[junit4] 2> 513982 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\tempDir-001/jetty3,
hostPort=51137,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-3-001\cores,
replicaType=TLOG}
[junit4] 2> 513982 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 513982 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 513982 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 513982 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 513982 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-15T12:34:31.075496100Z
[junit4] 2> 513993 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 0x15d463e15d7000d, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 513994 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 513994 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-3-001\solr.xml
[junit4] 2> 514037 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 514042 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51082/solr
[junit4] 2> 514047 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 0x15d463e15d7000e, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 514067 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 514070 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 514073 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51137_
[junit4] 2> 514076 INFO (zkCallback-991-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 514076 INFO (zkCallback-997-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 514077 INFO (zkCallback-1003-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 514077 INFO (zkCallback-979-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 514077 INFO (zkCallback-986-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 514172 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 514180 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 514180 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 514183 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-3-001\cores
[junit4] 2> 514234 INFO (qtp1541802432-6237) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:51137_&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 514254 INFO (OverseerThreadFactory-3178-thread-5) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:51137_ for creating new replica
[junit4] 2> 514259 INFO (qtp1503993007-6301) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_t1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 514260 INFO (qtp1503993007-6301) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 515277 INFO (qtp1503993007-6301) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 515306 INFO (qtp1503993007-6301) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_t1] Schema name=test
[junit4] 2> 515386 INFO (qtp1503993007-6301) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 515428 INFO (qtp1503993007-6301) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_t1' using configuration from
collection collection1, trusted=true
[junit4] 2> 515429 INFO (qtp1503993007-6301) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.replica_t1' (registry
'solr.core.collection1.shard2.replica_t1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 515429 INFO (qtp1503993007-6301) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 515430 INFO (qtp1503993007-6301) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_t1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-3-001\cores\collection1_shard2_replica_t1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-3-001\cores\collection1_shard2_replica_t1\data\]
[junit4] 2> 515444 INFO (qtp1503993007-6301) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=90.5986328125,
floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=29.909096492685613,
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 515451 WARN (qtp1503993007-6301) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 515543 INFO (qtp1503993007-6301) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 515543 INFO (qtp1503993007-6301) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 515544 INFO (qtp1503993007-6301) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 515545 INFO (qtp1503993007-6301) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 515549 INFO (qtp1503993007-6301) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=87.9814453125,
floorSegmentMB=0.9130859375, forceMergeDeletesPctAllowed=28.713299927934685,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.17693655700016617
[junit4] 2> 515550 INFO (qtp1503993007-6301) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@25479248[collection1_shard2_replica_t1] main]
[junit4] 2> 515552 INFO (qtp1503993007-6301) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 515553 INFO (qtp1503993007-6301) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 515553 INFO (qtp1503993007-6301) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 515553 INFO (qtp1503993007-6301) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1572992002446852096
[junit4] 2> 515557 INFO (searcherExecutor-3214-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_t1] Registered new searcher
Searcher@25479248[collection1_shard2_replica_t1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 515560 INFO (qtp1503993007-6301) [ ] o.a.s.c.ZkController
Core needs to recover:collection1_shard2_replica_t1
[junit4] 2> 515561 INFO (updateExecutor-1000-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 515562 INFO (qtp1503993007-6301) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_t1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=1304
[junit4] 2> 515563 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 515565 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 515565 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.ZkController collection1_shard2_replica_t1 stopping background
replication from leader
[junit4] 2> 515565 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1_shard2_replica_t1]
[junit4] 2> 515565 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 515565 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core
[collection1_shard2_replica_t1] as recovering, leader is
[http://127.0.0.1:51110/collection1_shard2_replica_t0/] and I am
[http://127.0.0.1:51137/collection1_shard2_replica_t1/]
[junit4] 2> 515566 INFO (qtp1541802432-6237) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:51137_&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2}
status=0 QTime=1331
[junit4] 2> 515571 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:51110]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard2_replica_t0&nodeName=127.0.0.1:51137_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 515581 INFO (qtp1541802432-6239) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 515581 INFO (qtp1541802432-6239) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1_shard2_replica_t0,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:51137_,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1_shard2_replica_t1","base_url":"http://127.0.0.1:51137","node_name":"127.0.0.1:51137_","state":"down","type":"TLOG"}
[junit4] 2> 516277 INFO
(OverseerCollectionConfigSetProcessor-98311999340347396-127.0.0.1:51089_-n_0000000000)
[ ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 516372 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-4-001
of type TLOG
[junit4] 2> 516373 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 516374 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3e933b2d{/,null,AVAILABLE}
[junit4] 2> 516377 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@17431c58{HTTP/1.1,[http/1.1]}{127.0.0.1:51151}
[junit4] 2> 516378 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.Server Started @517758ms
[junit4] 2> 516378 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/,
solrconfig=solrconfig.xml,
solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\tempDir-001/jetty4,
hostPort=51151,
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-4-001\cores,
replicaType=TLOG}
[junit4] 2> 516378 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 516378 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 516378 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 516378 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 516378 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-15T12:34:33.471957400Z
[junit4] 2> 516402 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 0x15d463e15d70010, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 516402 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 516402 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-4-001\solr.xml
[junit4] 2> 516407 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 516411 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51082/solr
[junit4] 2> 516420 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 0x15d463e15d70011, 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.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 516431 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 516433 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 516435 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51151_
[junit4] 2> 516438 INFO (zkCallback-991-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 516439 INFO (zkCallback-997-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 516440 INFO (zkCallback-1010-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 516440 INFO (zkCallback-979-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 516440 INFO (zkCallback-986-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 516440 INFO (zkCallback-1003-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 516524 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 516529 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 516529 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 516533 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-4-001\cores
[junit4] 2> 516576 INFO (qtp1541802432-6234) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:51151_&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 516580 INFO (OverseerThreadFactory-3178-thread-5) [ ]
o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:51151_ for creating new replica
[junit4] 2> 516582 INFO (qtp1541802432-6239) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1_shard2_replica_t0,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=recovering, localState=active, nodeName=127.0.0.1:51137_,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1_shard2_replica_t1","base_url":"http://127.0.0.1:51137","node_name":"127.0.0.1:51137_","state":"recovering","type":"TLOG"}
[junit4] 2> 516582 INFO (qtp1541802432-6239) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 516582 INFO (qtp1541802432-6239) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:51137_&onlyIfLeaderActive=true&core=collection1_shard2_replica_t0&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 516582 INFO (qtp1619708446-6326) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG
[junit4] 2> 516584 INFO (qtp1619708446-6326) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 517108 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting Replication Recovery.
[junit4] 2> 517108 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to replicate from
[http://127.0.0.1:51110/collection1_shard2_replica_t0/].
[junit4] 2> 517111 INFO (qtp1541802432-6236) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1572992004080533504,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 517116 INFO (qtp1541802432-6236) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 517118 INFO (qtp1541802432-6236) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 517118 INFO (qtp1541802432-6236) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_t0] webapp=
path=/update
params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2}{commit=}
0 6
[junit4] 2> 517124 INFO (qtp1541802432-6237) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_t0] webapp= path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replication Recovery was successful.
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.ZkController collection1_shard2_replica_t1 starting background
replication from leader
[junit4] 2> 517125 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.ReplicateFromLeader Will start replication from leader with poll
interval: 00:00:03
[junit4] 2> 517126 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 517139 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Updating version bucket highest from index after
successful recovery.
[junit4] 2> 517139 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1572992004109893632
[junit4] 2> 517139 INFO (recoveryExecutor-1001-thread-1) [ ]
o.a.s.c.RecoveryStrategy Finished recovery process, successful=[true]
[junit4] 2> 517638 INFO (qtp1619708446-6326) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 517656 INFO (qtp1619708446-6326) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_t1] Schema name=test
[junit4] 2> 517719 INFO (qtp1619708446-6326) [ ] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 517931 INFO (qtp1619708446-6326) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t1' using configuration from
collection collection1, trusted=true
[junit4] 2> 517932 INFO (qtp1619708446-6326) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_t1' (registry
'solr.core.collection1.shard1.replica_t1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@68288224
[junit4] 2> 517932 INFO (qtp1619708446-6326) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 517933 INFO (qtp1619708446-6326) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_t1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-4-001\cores\collection1_shard1_replica_t1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001\shard-4-001\cores\collection1_shard1_replica_t1\data\]
[junit4] 2> 517945 INFO (qtp1619708446-6326) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=90.5986328125,
floorSegmentMB=0.8515625, forceMergeDeletesPctAllowed=29.909096492685613,
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 517974 WARN (qtp1619708446-6326) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 518104 INFO (qtp1619708446-6326) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 518104 INFO (qtp1619708446-6326) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 518106 INFO (qtp1619708446-6326) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 518106 INFO (qtp1619708446-6326) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 518109 INFO (qtp1619708446-6326) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=87.9814453125,
floorSegmentMB=0.9130859375, forceMergeDeletesPctAllowed=28.713299927934685,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.17693655700016617
[junit4] 2> 518688 INFO (qtp1619708446-6326) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@68e817a8[collection1_shard1_replica_t1] main]
[junit4] 2> 518691 INFO (qtp1619708446-6326) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 518691 INFO (qtp1619708446-6326) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 518692 INFO (qtp1619708446-6326) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 518694 INFO (searcherExecutor-3227-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_t1] Registered new searcher
Searcher@68e817a8[collection1_shard1_replica_t1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 518694 INFO (qtp1619708446-6326) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1572992005740429312
[junit4] 2> 518699 INFO (qtp1619708446-6326) [ ] o.a.s.c.ZkController
Core needs to recover:collection1_shard1_replica_t1
[junit4] 2> 518702 INFO (qtp1619708446-6326) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_t1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=TLOG}
status=0 QTime=2120
[junit4] 2> 518705 INFO (qtp1541802432-6234) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={node=127.0.0.1:51151_&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
status=0 QTime=2128
[junit4] 2> 518709 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testSplitShardWithRule
[junit4] 2> 518709 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait 15
for each attempt
[junit4] 2> 518710 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):15
[junit4] 2> 518710 INFO (updateExecutor-1007-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 518711 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 518712 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 518712 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.c.ZkController collection1_shard1_replica_t1 stopping background
replication from leader
[junit4] 2> 518712 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1_shard1_replica_t1]
[junit4] 2> 518712 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 518712 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core
[collection1_shard1_replica_t1] as recovering, leader is
[http://127.0.0.1:51124/collection1_shard1_replica_t0/] and I am
[http://127.0.0.1:51151/collection1_shard1_replica_t1/]
[junit4] 2> 518716 INFO (recoveryExecutor-1008-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:51124]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard1_replica_t0&nodeName=127.0.0.1:51151_&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 518723 INFO (qtp243838020-6265) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 518724 INFO (qtp243838020-6265) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1_shard1_replica_t0,
leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true,
currentState=down, localState=active, nodeName=127.0.0.1:51151_,
coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: co
[...truncated too long message...]
plica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@76f7c843
[junit4] 2> 1162669 INFO (zkCallback-1195-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard2.replica_n1, tag=1995950147
[junit4] 2> 1162672 INFO (zkCallback-1195-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard2.leader, tag=1995950147
[junit4] 2> 1162673 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@31cee8e3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1162674 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@16d39673{/,null,UNAVAILABLE}
[junit4] 2> 1162674 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ChaosMonkey monkey: stop jetty! 52441
[junit4] 2> 1162675 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1681861885
[junit4] 2> 1162675 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1162675 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1162675 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1162677 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1162679 INFO (coreCloseExecutor-3858-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@b770abb
[junit4] 2> 1162682 INFO (zkCallback-1202-thread-5) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1162684 INFO (coreCloseExecutor-3858-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard1.replica_n1, tag=192350907
[junit4] 2> 1162686 INFO (coreCloseExecutor-3858-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard1.leader, tag=192350907
[junit4] 2> 1162687 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.Overseer Overseer (id=98312011496751122-127.0.0.1:52441_-n_0000000004)
closing
[junit4] 2> 1162687 INFO
(OverseerStateUpdate-98312011496751122-127.0.0.1:52441_-n_0000000004) [ ]
o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52441_
[junit4] 2> 1162692 WARN (zkCallback-1202-thread-5) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1162695 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@6ec4e51f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1162695 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7be149e{/,null,UNAVAILABLE}
[junit4] 2> 1162697 ERROR
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
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> 1162699 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[293125BE5DA80AC6]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:52314 52314
[junit4] 2> 1162700 INFO (Thread-2089) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:52314 52314
[junit4] 2> 1162700 WARN (Thread-2089) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2> 6 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 6 /solr/collections/collection1/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98312011496751113-127.0.0.1:52367_-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/collections
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ShardSplitTest
-Dtests.method=testSplitWithChaosMonkey -Dtests.seed=293125BE5DA80AC6
-Dtests.slow=true -Dtests.locale=ksh -Dtests.timezone=America/Bahia
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 474s J0 | ShardSplitTest.testSplitWithChaosMonkey <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 330 seconds
[junit4] > at
__randomizedtesting.SeedInfo.seed([293125BE5DA80AC6:A216F66F1CAEA142]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:183)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:140)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:135)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:907)
[junit4] > at
org.apache.solr.cloud.ShardSplitTest.testSplitWithChaosMonkey(ShardSplitTest.java:436)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ShardSplitTest_293125BE5DA80AC6-001
[junit4] 2> Jul 15, 2017 12:45:19 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=false): {}, locale=ksh, timezone=America/Bahia
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9-ea
(64-bit)/cpus=3,threads=1,free=163577864,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [SolrShardReporterTest,
TestXIncludeConfig, TestHighFrequencyDictionaryFactory,
TestDFRSimilarityFactory, TestTrieFacet, TestStreamBody,
QueryElevationComponentTest, XmlUpdateRequestHandlerTest, QueryParsingTest,
AliasIntegrationTest, CdcrBootstrapTest, TestRawResponseWriter,
TestCSVResponseWriter, DistributedFacetPivotLongTailTest, MinimalSchemaTest,
LeaderElectionContextKeyTest, TestSolrCoreProperties, CursorPagingTest,
TestDeleteCollectionOnDownNodes, TestLocalFSCloudBackupRestore,
DistributedFacetExistsSmallTest, DistributedSuggestComponentTest,
TestClassicSimilarityFactory, SegmentsInfoRequestHandlerTest,
TestCorePropertiesReload, TestSchemalessBufferedUpdates, AnalyticsQueryTest,
PropertiesRequestHandlerTest, ReplaceNodeTest, TestSolrCoreParser,
TestComplexPhraseLeadingWildcard, TestFilteredDocIdSet, ConnectionReuseTest,
DistribCursorPagingTest, TestReqParamsAPI, TestExceedMaxTermLength,
SpatialRPTFieldTypeTest, TestDynamicFieldResource, TestConfigOverlay,
TestApiFramework, TestHashPartitioner, InfoHandlerTest,
TestDownShardTolerantSearch, TestCloudPseudoReturnFields,
ShufflingReplicaListTransformerTest, CSVRequestHandlerTest,
SolrGraphiteReporterTest, SharedFSAutoReplicaFailoverUtilsTest,
TestDistributedStatsComponentCardinality, IndexSchemaTest,
TestPseudoReturnFields, RecoveryZkTest, SolrIndexMetricsTest,
TestNestedDocsSort, TestSolrCloudWithKerberosAlt, TestSolrDeletionPolicy2,
ClusterStateUpdateTest, TestCodecSupport, PeerSyncTest, TestSmileRequest,
V2StandaloneTest, TestStressCloudBlindAtomicUpdates,
AddSchemaFieldsUpdateProcessorFactoryTest, TestSearcherReuse,
CdcrVersionReplicationTest, SliceStateTest, TestFieldTypeResource,
TestRandomRequestDistribution, TestReplicationHandler, HighlighterTest,
PathHierarchyTokenizerFactoryTest, HdfsWriteToMultipleCollectionsTest,
ClusterStateTest, ShardSplitTest]
[junit4] Completed [231/728 (1!)] on J0 in 659.93s, 5 tests, 1 failure <<<
FAILURES!
[...truncated 38087 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
