Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.0-Linux/274/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseParallelGC
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([574C392DE811C2CE:DC6BEAFCA917694A]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:185)
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:908)
at
org.apache.solr.cloud.ShardSplitTest.testSplitWithChaosMonkey(ShardSplitTest.java:436)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:993)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java: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
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12189 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/init-core-data-001
[junit4] 2> 815036 WARN
(SUITE-ShardSplitTest-seed#[574C392DE811C2CE]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 815036 INFO
(SUITE-ShardSplitTest-seed#[574C392DE811C2CE]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 815037 INFO
(SUITE-ShardSplitTest-seed#[574C392DE811C2CE]-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> 815037 INFO
(SUITE-ShardSplitTest-seed#[574C392DE811C2CE]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /tap/o
[junit4] 2> 815039 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 815039 INFO (Thread-2420) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 815039 INFO (Thread-2420) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 815041 ERROR (Thread-2420) [ ] 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> 815139 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkTestServer start zk server on port:33129
[junit4] 2> 815148 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 815149 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 815153 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 815154 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 815155 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 815155 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 815156 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 815157 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 815157 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 815158 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 815159 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 815160 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly
asked otherwise
[junit4] 2> 815234 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 815235 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e8c9aa{/tap/o,null,AVAILABLE}
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@720d41{HTTP/1.1,[http/1.1]}{127.0.0.1:46855}
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server Started @816252ms
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/tempDir-001/control/data,
hostContext=/tap/o, hostPort=46855,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/control-001/cores}
[junit4] 2> 815237 ERROR
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 815237 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-29T09:17:09.424Z
[junit4] 2> 815239 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 815240 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/control-001/solr.xml
[junit4] 2> 815250 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 815253 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33129/solr
[junit4] 2> 815290 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 815290 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:46855_tap%2Fo
[junit4] 2> 815290 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.Overseer Overseer
(id=98566027950620676-127.0.0.1:46855_tap%2Fo-n_0000000000) starting
[junit4] 2> 815294 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46855_tap%2Fo
[junit4] 2> 815295 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 815495 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 815504 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 815504 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 815506 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/control-001/cores
[junit4] 2> 815532 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 815533 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33129/solr ready
[junit4] 2> 815534 INFO (qtp2812957-8205) [n:127.0.0.1:46855_tap%2Fo ]
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:46855_tap%252Fo&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 815536 INFO
(OverseerThreadFactory-3188-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.CreateCollectionCmd Create collection
control_collection
[junit4] 2> 815639 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo ]
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> 815639 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 815743 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] 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> 816650 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 816661 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 816724 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 816738 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from collection control_collection, trusted=true
[junit4] 2> 816739 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
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@2a5df6
[junit4] 2> 816742 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 816742 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 816745 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=92.2880859375,
floorSegmentMB=1.2861328125, forceMergeDeletesPctAllowed=6.265990120408947,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7539197250306016
[junit4] 2> 816759 WARN (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
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> 816801 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 816801 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 816801 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 816801 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 816803 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=8.6591796875,
floorSegmentMB=0.2880859375, forceMergeDeletesPctAllowed=9.850004483462666,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 816803 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@daff38[control_collection_shard1_replica_n1] main]
[junit4] 2> 816807 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 816808 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 816808 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 816810 INFO
(searcherExecutor-3191-thread-1-processing-n:127.0.0.1:46855_tap%2Fo
x:control_collection_shard1_replica_n1 s:shard1 c:control_collection)
[n:127.0.0.1:46855_tap%2Fo c:control_collection s:shard1
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher
Searcher@daff38[control_collection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 816810 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1577056449065910272
[junit4] 2> 816815 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 816815 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 816815 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:46855/tap/o/control_collection_shard1_replica_n1/
[junit4] 2> 816815 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 816815 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:46855/tap/o/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 816815 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 816817 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:46855/tap/o/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 816920 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] 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> 816968 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 816970 INFO (qtp2812957-8207) [n:127.0.0.1:46855_tap%2Fo
c:control_collection s:shard1 x:control_collection_shard1_replica_n1]
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=1331
[junit4] 2> 816975 INFO (qtp2812957-8205) [n:127.0.0.1:46855_tap%2Fo ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 817075 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] 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> 817538 INFO
(OverseerCollectionConfigSetProcessor-98566027950620676-127.0.0.1:46855_tap%2Fo-n_0000000000)
[n:127.0.0.1:46855_tap%2Fo ] 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> 817975 INFO (qtp2812957-8205) [n:127.0.0.1:46855_tap%2Fo ]
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:46855_tap%252Fo&wt=javabin&version=2}
status=0 QTime=2441
[junit4] 2> 817984 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 817985 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33129/solr ready
[junit4] 2> 817985 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 817987 INFO (qtp2812957-8205) [n:127.0.0.1:46855_tap%2Fo ]
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> 817991 INFO
(OverseerThreadFactory-3188-thread-2-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 817992 WARN
(OverseerThreadFactory-3188-thread-2-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.CreateCollectionCmd It is unusual to
create a collection (collection1) without cores.
[junit4] 2> 818196 INFO (qtp2812957-8205) [n:127.0.0.1:46855_tap%2Fo ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 818196 INFO (qtp2812957-8205) [n:127.0.0.1:46855_tap%2Fo ]
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=208
[junit4] 2> 818261 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-1-001
of type TLOG
[junit4] 2> 818261 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 818262 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@854285{/tap/o,null,AVAILABLE}
[junit4] 2> 818262 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3f0fd8{HTTP/1.1,[http/1.1]}{127.0.0.1:35841}
[junit4] 2> 818262 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server Started @819277ms
[junit4] 2> 818262 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/tempDir-001/jetty1,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/tap/o,
hostPort=35841,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-1-001/cores}
[junit4] 2> 818263 ERROR
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 818263 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 818263 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 818263 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 818263 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-29T09:17:12.450Z
[junit4] 2> 818264 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 818265 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-1-001/solr.xml
[junit4] 2> 818269 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 818271 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33129/solr
[junit4] 2> 818277 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 818278 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 818278 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35841_tap%2Fo
[junit4] 2> 818279 INFO
(zkCallback-1296-thread-1-processing-n:127.0.0.1:35841_tap%2Fo)
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 818279 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 818279 INFO (zkCallback-1291-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 818378 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 818388 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 818388 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 818390 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-1-001/cores
[junit4] 2> 818413 INFO (qtp2812957-8206) [n:127.0.0.1:46855_tap%2Fo ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params
node=127.0.0.1:35841_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 818415 INFO
(OverseerThreadFactory-3188-thread-3-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:35841_tap%2Fo for creating new replica
[junit4] 2> 818415 INFO
(OverseerCollectionConfigSetProcessor-98566027950620676-127.0.0.1:46855_tap%2Fo-n_0000000000)
[n:127.0.0.1:46855_tap%2Fo ] 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> 818417 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
] 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> 818417 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 819456 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 819477 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.s.IndexSchema
[collection1_shard2_replica_t0] Schema name=test
[junit4] 2> 819532 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 819542 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_t0' using configuration from
collection collection1, trusted=true
[junit4] 2> 819543 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
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@2a5df6
[junit4] 2> 819543 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 819543 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.SolrCore
[[collection1_shard2_replica_t0] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-1-001/cores/collection1_shard2_replica_t0],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-1-001/cores/collection1_shard2_replica_t0/data/]
[junit4] 2> 819546 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=92.2880859375,
floorSegmentMB=1.2861328125, forceMergeDeletesPctAllowed=6.265990120408947,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7539197250306016
[junit4] 2> 819565 WARN (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
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> 819605 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 819605 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 819606 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 819606 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 819607 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=8.6591796875,
floorSegmentMB=0.2880859375, forceMergeDeletesPctAllowed=9.850004483462666,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 819607 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3df1c6[collection1_shard2_replica_t0] main]
[junit4] 2> 819609 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 819609 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 819609 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 819610 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1577056452001923072
[junit4] 2> 819611 INFO
(searcherExecutor-3202-thread-1-processing-n:127.0.0.1:35841_tap%2Fo
x:collection1_shard2_replica_t0 s:shard2 c:collection1)
[n:127.0.0.1:35841_tap%2Fo c:collection1 s:shard2
x:collection1_shard2_replica_t0] o.a.s.c.SolrCore
[collection1_shard2_replica_t0] Registered new searcher
Searcher@3df1c6[collection1_shard2_replica_t0]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 819614 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 819614 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 819614 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:35841/tap/o/collection1_shard2_replica_t0/
[junit4] 2> 819614 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 819614 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.SyncStrategy
http://127.0.0.1:35841/tap/o/collection1_shard2_replica_t0/ has no replicas
[junit4] 2> 819615 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 819615 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.ZkController
collection1_shard2_replica_t0 stopping background replication from leader
[junit4] 2> 819616 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:35841/tap/o/collection1_shard2_replica_t0/ shard2
[junit4] 2> 819767 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 819769 INFO (qtp29553001-8246) [n:127.0.0.1:35841_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t0] 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=1352
[junit4] 2> 819775 INFO (qtp2812957-8206) [n:127.0.0.1:46855_tap%2Fo ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:35841_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2}
status=0 QTime=1361
[junit4] 2> 819864 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-2-001
of type TLOG
[junit4] 2> 819864 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 819865 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@8a028f{/tap/o,null,AVAILABLE}
[junit4] 2> 819869 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1af0349{HTTP/1.1,[http/1.1]}{127.0.0.1:37743}
[junit4] 2> 819869 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server Started @820884ms
[junit4] 2> 819869 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/tempDir-001/jetty2,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/tap/o,
hostPort=37743,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-2-001/cores}
[junit4] 2> 819870 ERROR
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 819870 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 819870 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 819870 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 819870 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-29T09:17:14.057Z
[junit4] 2> 819872 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 819872 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-2-001/solr.xml
[junit4] 2> 819876 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 819878 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33129/solr
[junit4] 2> 819884 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 819885 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 819886 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37743_tap%2Fo
[junit4] 2> 819887 INFO (zkCallback-1291-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 819887 INFO
(zkCallback-1296-thread-1-processing-n:127.0.0.1:35841_tap%2Fo)
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 819887 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 819887 INFO
(zkCallback-1302-thread-1-processing-n:127.0.0.1:37743_tap%2Fo)
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 820023 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 820034 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 820034 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 820036 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-2-001/cores
[junit4] 2> 820072 INFO (qtp31695458-8275) [n:127.0.0.1:37743_tap%2Fo
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:37743_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 820074 INFO
(OverseerCollectionConfigSetProcessor-98566027950620676-127.0.0.1:46855_tap%2Fo-n_0000000000)
[n:127.0.0.1:46855_tap%2Fo ] 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> 820074 INFO
(OverseerThreadFactory-3188-thread-4-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:37743_tap%2Fo for creating new replica
[junit4] 2> 820075 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
] 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> 820076 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 821096 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 821119 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.s.IndexSchema
[collection1_shard1_replica_t0] Schema name=test
[junit4] 2> 821179 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 821189 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t0' using configuration from
collection collection1, trusted=true
[junit4] 2> 821190 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
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@2a5df6
[junit4] 2> 821190 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 821190 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.SolrCore
[[collection1_shard1_replica_t0] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-2-001/cores/collection1_shard1_replica_t0],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-2-001/cores/collection1_shard1_replica_t0/data/]
[junit4] 2> 821193 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=92.2880859375,
floorSegmentMB=1.2861328125, forceMergeDeletesPctAllowed=6.265990120408947,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7539197250306016
[junit4] 2> 821217 WARN (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
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> 821275 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 821275 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 821276 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 821276 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 821277 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=8.6591796875,
floorSegmentMB=0.2880859375, forceMergeDeletesPctAllowed=9.850004483462666,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 821278 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@15a509c[collection1_shard1_replica_t0] main]
[junit4] 2> 821279 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 821279 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 821280 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 821280 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1577056453753044992
[junit4] 2> 821281 INFO
(searcherExecutor-3213-thread-1-processing-n:127.0.0.1:37743_tap%2Fo
x:collection1_shard1_replica_t0 s:shard1 c:collection1)
[n:127.0.0.1:37743_tap%2Fo c:collection1 s:shard1
x:collection1_shard1_replica_t0] o.a.s.c.SolrCore
[collection1_shard1_replica_t0] Registered new searcher
Searcher@15a509c[collection1_shard1_replica_t0]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 821284 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 821284 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 821284 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:37743/tap/o/collection1_shard1_replica_t0/
[junit4] 2> 821284 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 821284 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.SyncStrategy
http://127.0.0.1:37743/tap/o/collection1_shard1_replica_t0/ has no replicas
[junit4] 2> 821284 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 821285 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.ZkController
collection1_shard1_replica_t0 stopping background replication from leader
[junit4] 2> 821286 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:37743/tap/o/collection1_shard1_replica_t0/ shard1
[junit4] 2> 821436 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 821439 INFO (qtp31695458-8277) [n:127.0.0.1:37743_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t0] 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=1363
[junit4] 2> 821442 INFO (qtp31695458-8275) [n:127.0.0.1:37743_tap%2Fo
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:37743_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2}
status=0 QTime=1370
[junit4] 2> 821527 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-3-001
of type TLOG
[junit4] 2> 821528 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 821529 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@60f5ee{/tap/o,null,AVAILABLE}
[junit4] 2> 821529 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@d5f258{HTTP/1.1,[http/1.1]}{127.0.0.1:34383}
[junit4] 2> 821529 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server Started @822544ms
[junit4] 2> 821529 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/tempDir-001/jetty3,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/tap/o,
hostPort=34383,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-3-001/cores}
[junit4] 2> 821530 ERROR
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 821530 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 821530 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 821530 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 821530 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-29T09:17:15.717Z
[junit4] 2> 821532 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 821532 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-3-001/solr.xml
[junit4] 2> 821535 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 821538 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33129/solr
[junit4] 2> 821543 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 821543 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 821544 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:34383_tap%2Fo
[junit4] 2> 821545 INFO
(zkCallback-1302-thread-1-processing-n:127.0.0.1:37743_tap%2Fo)
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 821545 INFO
(zkCallback-1284-thread-1-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 821545 INFO (zkCallback-1291-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 821545 INFO
(zkCallback-1296-thread-1-processing-n:127.0.0.1:35841_tap%2Fo)
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 821545 INFO
(zkCallback-1308-thread-1-processing-n:127.0.0.1:34383_tap%2Fo)
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 821661 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 821671 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 821672 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 821673 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-3-001/cores
[junit4] 2> 821713 INFO (qtp31695458-8276) [n:127.0.0.1:37743_tap%2Fo
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:34383_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 821716 INFO
(OverseerThreadFactory-3188-thread-5-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:34383_tap%2Fo for creating new replica
[junit4] 2> 821716 INFO
(OverseerCollectionConfigSetProcessor-98566027950620676-127.0.0.1:46855_tap%2Fo-n_0000000000)
[n:127.0.0.1:46855_tap%2Fo ] 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> 821717 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
] 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> 821718 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 822728 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 822738 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.s.IndexSchema
[collection1_shard2_replica_t1] Schema name=test
[junit4] 2> 822814 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 822828 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_t1' using configuration from
collection collection1, trusted=true
[junit4] 2> 822828 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
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@2a5df6
[junit4] 2> 822828 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 822828 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.c.SolrCore
[[collection1_shard2_replica_t1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-3-001/cores/collection1_shard2_replica_t1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-3-001/cores/collection1_shard2_replica_t1/data/]
[junit4] 2> 822831 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=92.2880859375,
floorSegmentMB=1.2861328125, forceMergeDeletesPctAllowed=6.265990120408947,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7539197250306016
[junit4] 2> 822850 WARN (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
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> 822885 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 822885 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 822886 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 822886 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 822887 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=36, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=8.6591796875,
floorSegmentMB=0.2880859375, forceMergeDeletesPctAllowed=9.850004483462666,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 822888 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@17d677e[collection1_shard2_replica_t1] main]
[junit4] 2> 822888 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 822889 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 822889 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 822890 INFO
(searcherExecutor-3224-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2
x:collection1_shard2_replica_t1] o.a.s.c.SolrCore
[collection1_shard2_replica_t1] Registered new searcher
Searcher@17d677e[collection1_shard2_replica_t1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 822891 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1577056455441252352
[junit4] 2> 822893 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] o.a.s.c.ZkController
Core needs to recover:collection1_shard2_replica_t1
[junit4] 2> 822893 INFO
(updateExecutor-1305-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 822893 INFO (qtp20356992-8304) [n:127.0.0.1:34383_tap%2Fo
c:collection1 s:shard2 x:collection1_shard2_replica_t1] 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=1175
[junit4] 2> 822893 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 822894 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.c.RecoveryStrategy ######
startupVersions=[[]]
[junit4] 2> 822894 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.c.ZkController
collection1_shard2_replica_t1 stopping background replication from leader
[junit4] 2> 822894 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.c.RecoveryStrategy Begin buffering
updates. core=[collection1_shard2_replica_t1]
[junit4] 2> 822894 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 822894 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.c.RecoveryStrategy Publishing state of
core [collection1_shard2_replica_t1] as recovering, leader is
[http://127.0.0.1:35841/tap/o/collection1_shard2_replica_t0/] and I am
[http://127.0.0.1:34383/tap/o/collection1_shard2_replica_t1/]
[junit4] 2> 822896 INFO (qtp31695458-8276) [n:127.0.0.1:37743_tap%2Fo
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={node=127.0.0.1:34383_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard2&type=TLOG&wt=javabin&version=2}
status=0 QTime=1182
[junit4] 2> 822897 INFO
(recoveryExecutor-1306-thread-1-processing-n:127.0.0.1:34383_tap%2Fo
x:collection1_shard2_replica_t1 s:shard2 c:collection1 r:core_node3)
[n:127.0.0.1:34383_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_t1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:35841/tap/o]; [WaitForState:
action=PREPRECOVERY&core=collection1_shard2_replica_t0&nodeName=127.0.0.1:34383_tap%252Fo&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 822898 INFO (qtp29553001-8248) [n:127.0.0.1:35841_tap%2Fo
] 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> 822899 INFO (qtp29553001-8248) [n:127.0.0.1:35841_tap%2Fo
] 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:34383_tap%2Fo,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1_shard2_replica_t1","base_url":"http://127.0.0.1:34383/tap/o","node_name":"127.0.0.1:34383_tap%2Fo","state":"down","type":"TLOG"}
[junit4] 2> 822958 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-4-001
of type TLOG
[junit4] 2> 822958 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 822959 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@12d258b{/tap/o,null,AVAILABLE}
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@123e17b{HTTP/1.1,[http/1.1]}{127.0.0.1:38465}
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.e.j.s.Server Started @823975ms
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/tempDir-001/jetty4,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/tap/o,
hostPort=38465,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-4-001/cores}
[junit4] 2> 822960 ERROR
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 822960 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-08-29T09:17:17.147Z
[junit4] 2> 822962 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 822962 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-4-001/solr.xml
[junit4] 2> 822966 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 822968 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33129/solr
[junit4] 2> 822974 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 822975 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 822976 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38465_tap%2Fo
[junit4] 2> 822976 INFO
(zkCallback-1302-thread-1-processing-n:127.0.0.1:37743_tap%2Fo)
[n:127.0.0.1:37743_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 822976 INFO
(zkCallback-1308-thread-1-processing-n:127.0.0.1:34383_tap%2Fo)
[n:127.0.0.1:34383_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 822976 INFO
(zkCallback-1296-thread-1-processing-n:127.0.0.1:35841_tap%2Fo)
[n:127.0.0.1:35841_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 822977 INFO
(zkCallback-1315-thread-1-processing-n:127.0.0.1:38465_tap%2Fo)
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 822977 INFO
(zkCallback-1284-thread-2-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 822976 INFO (zkCallback-1291-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 823073 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 823084 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 823085 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2a5df6
[junit4] 2> 823086 INFO
(TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[574C392DE811C2CE])
[n:127.0.0.1:38465_tap%2Fo ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-4-001/cores
[junit4] 2> 823145 INFO (qtp31695458-8271) [n:127.0.0.1:37743_tap%2Fo
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with
params
node=127.0.0.1:38465_tap%252Fo&action=ADDREPLICA&collection=collection1&shard=shard1&type=TLOG&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 823147 INFO
(OverseerThreadFactory-3188-thread-5-processing-n:127.0.0.1:46855_tap%2Fo)
[n:127.0.0.1:46855_tap%2Fo ] o.a.s.c.AddReplicaCmd Node Identified
127.0.0.1:38465_tap%2Fo for creating new replica
[junit4] 2> 823147 INFO
(OverseerCollectionConfigSetProcessor-98566027950620676-127.0.0.1:46855_tap%2Fo-n_0000000000)
[n:127.0.0.1:46855_tap%2Fo ] 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> 823148 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
] 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> 823149 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 823899 INFO (qtp29553001-8248) [n:127.0.0.1:35841_tap%2Fo
] 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:34383_tap%2Fo,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1_shard2_replica_t1","base_url":"http://127.0.0.1:34383/tap/o","node_name":"127.0.0.1:34383_tap%2Fo","state":"recovering","type":"TLOG"}
[junit4] 2> 823899 INFO (qtp29553001-8248) [n:127.0.0.1:35841_tap%2Fo
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 823899 INFO (qtp29553001-8248) [n:127.0.0.1:35841_tap%2Fo
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:34383_tap%252Fo&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=1001
[junit4] 2> 824161 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 824171 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1] o.a.s.s.IndexSchema
[collection1_shard1_replica_t1] Schema name=test
[junit4] 2> 824231 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 824241 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_t1' using configuration from
collection collection1, trusted=true
[junit4] 2> 824242 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1]
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@2a5df6
[junit4] 2> 824242 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 824242 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1] o.a.s.c.SolrCore
[[collection1_shard1_replica_t1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-4-001/cores/collection1_shard1_replica_t1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001/shard-4-001/cores/collection1_shard1_replica_t1/data/]
[junit4] 2> 824244 INFO (qtp10134133-8338) [n:127.0.0.1:38465_tap%2Fo
c:collection1 s:shard1 x:collection1_shard1_replica_t1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExpl
[...truncated too long message...]
m.codahale.metrics.MetricRegistry@98614f
[junit4] 2> 1578999 INFO
(zkCallback-1496-thread-2-processing-n:127.0.0.1:41705_tap%2Fo)
[n:127.0.0.1:41705_tap%2Fo c:collection1 s:shard2 r:core_node3
x:collection1_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard2.leader, tag=20856421
[junit4] 2> 1579000 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@1cd1ef{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1579001 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@494a43{/tap/o,null,UNAVAILABLE}
[junit4] 2> 1579001 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.c.ChaosMonkey monkey: stop jetty! 45433
[junit4] 2> 1579001 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1877432
[junit4] 2> 1579001 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1579001 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@111e781: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@1598720
[junit4] 2> 1579002 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1579002 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@ee57c3: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@ffbff5
[junit4] 2> 1579002 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.jetty, tag=null
[junit4] 2> 1579002 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d8ec4c: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@c43574
[junit4] 2> 1579003 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 1579004 INFO (coreCloseExecutor-3868-thread-1)
[n:127.0.0.1:45433_tap%2Fo c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1059070
[junit4] 2> 1579004 INFO (coreCloseExecutor-3868-thread-1)
[n:127.0.0.1:45433_tap%2Fo c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.collection1.shard1.replica_n1, tag=17141872
[junit4] 2> 1579004 INFO (coreCloseExecutor-3868-thread-1)
[n:127.0.0.1:45433_tap%2Fo c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1c12667: rootName = null,
domain = solr.core.collection1.shard1.replica_n1, service url = null, agent id
= null] for registry solr.core.collection1.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@1b75229
[junit4] 2> 1579013 INFO (coreCloseExecutor-3868-thread-1)
[n:127.0.0.1:45433_tap%2Fo c:collection1 s:shard1 r:core_node4
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.collection1.shard1.leader, tag=17141872
[junit4] 2> 1579015 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.c.Overseer Overseer
(id=98566075715813394-127.0.0.1:45433_tap%2Fo-n_0000000004) closing
[junit4] 2> 1579015 INFO
(OverseerStateUpdate-98566075715813394-127.0.0.1:45433_tap%2Fo-n_0000000004)
[n:127.0.0.1:45433_tap%2Fo ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:45433_tap%2Fo
[junit4] 2> 1579016 WARN
(zkCallback-1503-thread-1-processing-n:127.0.0.1:45433_tap%2Fo)
[n:127.0.0.1:45433_tap%2Fo ] o.a.s.c.c.ZkStateReader ZooKeeper watch
triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/live_nodes]
[junit4] 2> 1579016 INFO (zkCallback-1479-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 1579017 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@5f1e7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1579017 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1f7abe2{/tap/o,null,UNAVAILABLE}
[junit4] 2> 1579018 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.c.ChaosMonkey monkey: stop jetty! 40107
[junit4] 2> 1579018 ERROR
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] 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> 1579018 INFO
(TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[574C392DE811C2CE]) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36505 36505
[junit4] 2> 1584100 INFO (Thread-2674) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:36505 36505
[junit4] 2> 1584101 WARN (Thread-2674) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/aliases.json
[junit4] 2> 6 /solr/security.json
[junit4] 2> 6 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 9 /solr/clusterstate.json
[junit4] 2> 5 /solr/collections/collection1/state.json
[junit4] 2> 4
/solr/collections/testSplitStaticIndexReplication/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98566075715813385-127.0.0.1:40227_tap%2Fo-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/collections
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-7.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_574C392DE811C2CE-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=true): {}, locale=be-BY, timezone=Pacific/Yap
[junit4] 2> NOTE: Linux 4.10.0-27-generic i386/Oracle Corporation
1.8.0_144 (32-bit)/cpus=8,threads=1,free=96032944,total=458489856
[junit4] 2> NOTE: All tests run in this JVM:
[XsltUpdateRequestHandlerTest, HLLUtilTest, QueryEqualityTest,
TestPivotHelperCode, DistributedFacetPivotSmallTest,
BigEndianAscendingWordDeserializerTest, TestSort, CollectionsAPISolrJTest,
TestCloudInspectUtil, TestTestInjection, TestTolerantSearch,
CollectionStateFormat2Test, TestStressReorder, CdcrReplicationHandlerTest,
DistributedFacetPivotLargeTest, TestStandardQParsers, TestSearcherReuse,
SolrTestCaseJ4Test, TestLegacyTerms, DocValuesMissingTest, TestFieldCacheSort,
SolrJmxReporterTest, PeerSyncWithIndexFingerprintCachingTest,
TestFieldCollectionResource, TestHdfsBackupRestoreCore, SuggesterTest,
OverseerRolesTest, TestUniqueKeyFieldResource, TestScoreJoinQPScore,
HdfsRestartWhileUpdatingTest, OverseerTest, SolrMetricManagerTest,
TestRestoreCore, TestQuerySenderListener, TestShortCircuitedRequests,
TestReload, LeaderInitiatedRecoveryOnShardRestartTest,
DistributedQueryComponentOptimizationTest, PathHierarchyTokenizerFactoryTest,
ExplicitHLLTest, TestPKIAuthenticationPlugin, TestZkChroot,
TestCorePropertiesReload, DeleteInactiveReplicaTest, TestUtils, TestLFUCache,
ConnectionManagerTest, TestManagedSchema, TestCodecSupport, TestQueryUtils,
TestAddFieldRealTimeGet, TestDistributedMissingSort, SolrShardReporterTest,
TestFieldCacheWithThreads, RequiredFieldsTest, NotRequiredUniqueKeyTest,
RegexBoostProcessorTest, TestXmlQParser, LeaderElectionTest,
TestZkAclsWithHadoopAuth, TestImplicitCoreProperties, TestPointFields,
AnalysisErrorHandlingTest, CleanupOldIndexTest, SearchHandlerTest,
PreAnalyzedFieldTest, TestHdfsCloudBackupRestore, TestMiniSolrCloudCluster,
TestChildDocTransformer, HdfsNNFailoverTest, TestCopyFieldCollectionResource,
TestOverriddenPrefixQueryForCustomFieldType,
TermVectorComponentDistributedTest, TestCollectionAPI, TestFieldTypeResource,
QueryResultKeyTest, DistribCursorPagingTest, PrimitiveFieldTypeTest,
BadIndexSchemaTest, CloneFieldUpdateProcessorFactoryTest,
TestTolerantUpdateProcessorCloud, StressHdfsTest, TestQueryWrapperFilter,
ClusterStateTest, TestLeaderInitiatedRecoveryThread, ShardSplitTest]
[junit4] Completed [413/729 (1!)] on J2 in 769.10s, 5 tests, 1 failure <<<
FAILURES!
[...truncated 40457 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
