Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/297/
2 tests failed.
FAILED: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test
Error Message:
document count mismatch. control=305 sum(shards)=304 cloudClient=304
Stack Trace:
java.lang.AssertionError: document count mismatch. control=305 sum(shards)=304
cloudClient=304
at
__randomizedtesting.SeedInfo.seed([C54B093F53762F4F:4D1F36E5FD8A42B7]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1332)
at
org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test(ChaosMonkeyNothingIsSafeTest.java:226)
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:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
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:745)
FAILED: junit.framework.TestSuite.org.apache.solr.update.UpdateLogTest
Error Message:
Clean up static fields (in @AfterClass?), your test seems to hang on to
approximately 20,874,344 bytes (threshold is 10,485,760). Field reference sizes
(counted individually): - 20,874,016 bytes, static
org.apache.solr.update.UpdateLog org.apache.solr.update.UpdateLogTest.ulog -
192 bytes, public static org.junit.rules.TestRule
org.apache.solr.SolrTestCaseJ4.solrClassRules - 128 bytes, private static
java.lang.String org.apache.solr.SolrTestCaseJ4.factoryProp - 64 bytes,
private static java.lang.String org.apache.solr.SolrTestCaseJ4.coreName - 48
bytes, private static java.util.Map
org.apache.solr.SolrTestCaseJ4.savedClassLogLevels
Stack Trace:
junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?),
your test seems to hang on to approximately 20,874,344 bytes (threshold is
10,485,760). Field reference sizes (counted individually):
- 20,874,016 bytes, static org.apache.solr.update.UpdateLog
org.apache.solr.update.UpdateLogTest.ulog
- 192 bytes, public static org.junit.rules.TestRule
org.apache.solr.SolrTestCaseJ4.solrClassRules
- 128 bytes, private static java.lang.String
org.apache.solr.SolrTestCaseJ4.factoryProp
- 64 bytes, private static java.lang.String
org.apache.solr.SolrTestCaseJ4.coreName
- 48 bytes, private static java.util.Map
org.apache.solr.SolrTestCaseJ4.savedClassLogLevels
at __randomizedtesting.SeedInfo.seed([C54B093F53762F4F]:0)
at
com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:170)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:745)
Build Log:
[...truncated 12851 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/init-core-data-001
[junit4] 2> 2496498 INFO
(SUITE-ChaosMonkeyNothingIsSafeTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 2496498 INFO
(SUITE-ChaosMonkeyNothingIsSafeTest-seed#[C54B093F53762F4F]-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> 2496498 INFO
(SUITE-ChaosMonkeyNothingIsSafeTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2496500 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2496501 INFO (Thread-6984) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2496501 INFO (Thread-6984) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2496658 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkTestServer start zk server on port:49483
[junit4] 2> 2496672 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 2496674 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 2496675 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2496677 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 2496678 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 2496679 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 2496682 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 2496683 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2496687 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2496688 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 2496689 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 2496787 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/control-001/cores/collection1
[junit4] 2> 2496788 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2496789 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6ab93810{/,null,AVAILABLE}
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3fcdc930{HTTP/1.1,[http/1.1]}{127.0.0.1:53370}
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server Started @2500036ms
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/tempDir-001/control/data,
hostContext=/, hostPort=53370,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/control-001/cores}
[junit4] 2> 2496790 ERROR
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2496790 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-28T16:19:20.085Z
[junit4] 2> 2496799 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2496799 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/control-001/solr.xml
[junit4] 2> 2496804 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2496805 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49483/solr
[junit4] 2> 2496819 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:53370_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2496820 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:53370_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:53370_
[junit4] 2> 2496821 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:53370_ ] o.a.s.c.Overseer Overseer
(id=97537147522056196-127.0.0.1:53370_-n_0000000000) starting
[junit4] 2> 2496827 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:53370_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53370_
[junit4] 2> 2496831 INFO
(zkCallback-1942-thread-1-processing-n:127.0.0.1:53370_) [n:127.0.0.1:53370_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2496888 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:53370_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/control-001/cores
[junit4] 2> 2496888 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:53370_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2496912 INFO
(OverseerStateUpdate-97537147522056196-127.0.0.1:53370_-n_0000000000)
[n:127.0.0.1:53370_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2496925 WARN
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 2496926 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.5.0
[junit4] 2> 2496970 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2497209 WARN
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 2497210 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2497220 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 2497221 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/control-001/cores/collection1/data/]
[junit4] 2> 2497221 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d00075b
[junit4] 2> 2497222 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@2222eb24
[junit4] 2> 2497231 WARN
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
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> 2497285 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2497285 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2497285 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 2497285 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2497286 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=8763921,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 2497287 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4a950fd2[collection1] main]
[junit4] 2> 2497288 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2497288 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2497288 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2497289 INFO
(searcherExecutor-6918-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4a950fd2[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2497289 INFO
(coreLoadExecutor-6917-thread-1-processing-n:127.0.0.1:53370_)
[n:127.0.0.1:53370_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1560594361178128384
[junit4] 2> 2497296 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 2497296 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new
leader - try and sync
[junit4] 2> 2497296 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:53370/collection1/
[junit4] 2> 2497296 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 2497296 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:53370/collection1/ has no replicas
[junit4] 2> 2497296 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 2497298 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:53370/collection1/ shard1
[junit4] 2> 2497449 INFO
(coreZkRegister-6910-thread-1-processing-n:127.0.0.1:53370_ x:collection1
c:control_collection) [n:127.0.0.1:53370_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 2497836 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2497837 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49483/solr ready
[junit4] 2> 2497837 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 2497907 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001/cores/collection1
[junit4] 2> 2497908 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001
[junit4] 2> 2497908 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2497909 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4627f274{/,null,AVAILABLE}
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3d6721ed{HTTP/1.1,[http/1.1]}{127.0.0.1:50432}
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server Started @2501155ms
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=50432,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001/cores}
[junit4] 2> 2497910 ERROR
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2497910 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-28T16:19:21.205Z
[junit4] 2> 2497928 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2497928 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001/solr.xml
[junit4] 2> 2497935 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2497936 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49483/solr
[junit4] 2> 2497944 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:50432_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2497946 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:50432_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2497947 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:50432_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50432_
[junit4] 2> 2497948 INFO (zkCallback-1946-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2497949 INFO
(zkCallback-1942-thread-1-processing-n:127.0.0.1:53370_) [n:127.0.0.1:53370_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2497957 INFO
(zkCallback-1951-thread-1-processing-n:127.0.0.1:50432_) [n:127.0.0.1:50432_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2498003 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:50432_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001/cores
[junit4] 2> 2498003 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:50432_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2498004 INFO
(OverseerStateUpdate-97537147522056196-127.0.0.1:53370_-n_0000000000)
[n:127.0.0.1:53370_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2499014 WARN
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2499014 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 2499025 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2499096 WARN
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 2499097 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 2499166 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 2499166 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 2499166 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d00075b
[junit4] 2> 2499168 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@2e06d251
[junit4] 2> 2499182 WARN
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] 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> 2499212 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2499213 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2499213 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: if uncommited for 15000ms;
[junit4] 2> 2499213 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2499214 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=8763921,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 2499215 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@3e0d292a[collection1] main]
[junit4] 2> 2499215 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2499216 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2499216 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 2499216 INFO
(coreLoadExecutor-6928-thread-1-processing-n:127.0.0.1:50432_)
[n:127.0.0.1:50432_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560594363198734336
[junit4] 2> 2499218 INFO
(searcherExecutor-6929-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3e0d292a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2499225 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 2499225 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 2499225 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:50432/collection1/
[junit4] 2> 2499225 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2499225 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:50432/collection1/ has no
replicas
[junit4] 2> 2499225 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 2499229 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:50432/collection1/ shard1
[junit4] 2> 2499380 INFO
(coreZkRegister-6923-thread-1-processing-n:127.0.0.1:50432_ x:collection1
c:collection1) [n:127.0.0.1:50432_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2499504 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001/cores/collection1
[junit4] 2> 2499504 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001
[junit4] 2> 2499505 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2499506 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1d72e6c8{/,null,AVAILABLE}
[junit4] 2> 2499506 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@56a044ff{HTTP/1.1,[http/1.1]}{127.0.0.1:46492}
[junit4] 2> 2499507 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server Started @2502753ms
[junit4] 2> 2499507 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/, hostPort=46492,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001/cores}
[junit4] 2> 2499507 ERROR
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2499508 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 2499508 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2499508 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2499508 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-28T16:19:22.803Z
[junit4] 2> 2499514 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2499514 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001/solr.xml
[junit4] 2> 2499519 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2499519 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49483/solr
[junit4] 2> 2499528 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:46492_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2499532 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:46492_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2499539 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:46492_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46492_
[junit4] 2> 2499540 INFO
(zkCallback-1951-thread-1-processing-n:127.0.0.1:50432_) [n:127.0.0.1:50432_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2499540 INFO (zkCallback-1946-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2499540 INFO
(zkCallback-1942-thread-1-processing-n:127.0.0.1:53370_) [n:127.0.0.1:53370_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2499545 INFO
(zkCallback-1957-thread-1-processing-n:127.0.0.1:46492_) [n:127.0.0.1:46492_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2499616 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:46492_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001/cores
[junit4] 2> 2499616 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:46492_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2499622 INFO
(OverseerStateUpdate-97537147522056196-127.0.0.1:53370_-n_0000000000)
[n:127.0.0.1:53370_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2500666 WARN
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2500667 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 2500679 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2500736 WARN
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 2500741 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 2500752 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 2500752 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 2500752 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d00075b
[junit4] 2> 2500754 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@5c5870ca
[junit4] 2> 2500767 WARN
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] 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> 2500875 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2500875 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2500877 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: if uncommited for 15000ms;
[junit4] 2> 2500877 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2500878 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=8763921,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 2500878 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@47c323e1[collection1] main]
[junit4] 2> 2500879 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2500879 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2500879 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 2500880 INFO
(searcherExecutor-6940-thread-1-processing-n:127.0.0.1:46492_ x:collection1
c:collection1) [n:127.0.0.1:46492_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@47c323e1[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2500881 INFO
(coreLoadExecutor-6939-thread-1-processing-n:127.0.0.1:46492_)
[n:127.0.0.1:46492_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560594364944613376
[junit4] 2> 2500885 INFO
(coreZkRegister-6934-thread-1-processing-n:127.0.0.1:46492_ x:collection1
c:collection1) [n:127.0.0.1:46492_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 2500886 INFO
(updateExecutor-1954-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 2500888 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 2500888 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 2500888 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 2500888 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2500888 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core
[collection1] as recovering, leader is [http://127.0.0.1:50432/collection1/]
and I am [http://127.0.0.1:46492/collection1/]
[junit4] 2> 2500893 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:50432]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:46492_&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 2500896 INFO (qtp1736091904-17594) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 2500897 INFO (qtp1736091904-17594) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 2500897 INFO (qtp1736091904-17594) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:46492_, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:46492","node_name":"127.0.0.1:46492_","state":"down"}
[junit4] 2> 2501031 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001/cores/collection1
[junit4] 2> 2501031 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001
[junit4] 2> 2501032 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2501033 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e2a5f7{/,null,AVAILABLE}
[junit4] 2> 2501033 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@e414c63{HTTP/1.1,[http/1.1]}{127.0.0.1:45222}
[junit4] 2> 2501033 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server Started @2504279ms
[junit4] 2> 2501034 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/, hostPort=45222,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001/cores}
[junit4] 2> 2501034 ERROR
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2501034 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 2501034 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2501034 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2501034 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-28T16:19:24.329Z
[junit4] 2> 2501038 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2501038 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001/solr.xml
[junit4] 2> 2501043 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2501045 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49483/solr
[junit4] 2> 2501053 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:45222_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 2501055 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:45222_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2501057 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:45222_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45222_
[junit4] 2> 2501058 INFO (zkCallback-1946-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2501058 INFO
(zkCallback-1951-thread-1-processing-n:127.0.0.1:50432_) [n:127.0.0.1:50432_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2501058 INFO
(zkCallback-1942-thread-3-processing-n:127.0.0.1:53370_) [n:127.0.0.1:53370_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2501058 INFO
(zkCallback-1957-thread-1-processing-n:127.0.0.1:46492_) [n:127.0.0.1:46492_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2501059 INFO
(zkCallback-1964-thread-1-processing-n:127.0.0.1:45222_) [n:127.0.0.1:45222_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2501283 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:45222_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001/cores
[junit4] 2> 2501283 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:45222_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2501290 INFO
(OverseerStateUpdate-97537147522056196-127.0.0.1:53370_-n_0000000000)
[n:127.0.0.1:53370_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2501897 INFO (qtp1736091904-17594) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:46492_, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:46492","node_name":"127.0.0.1:46492_","state":"recovering"}
[junit4] 2> 2501897 INFO (qtp1736091904-17594) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 2501897 INFO (qtp1736091904-17594) [n:127.0.0.1:50432_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:46492_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1001
[junit4] 2> 2502297 WARN
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2502298 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 2502332 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2502391 WARN
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 2502394 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 2502398 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Attempting to PeerSync
from [http://127.0.0.1:50432/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 2502398 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.PeerSync PeerSync: core=collection1
url=http://127.0.0.1:46492 START replicas=[http://127.0.0.1:50432/collection1/]
nUpdates=100
[junit4] 2> 2502407 INFO (qtp1736091904-17592) [n:127.0.0.1:50432_
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 2502407 INFO (qtp1736091904-17592) [n:127.0.0.1:50432_
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp= path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2502408 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.IndexFingerprint IndexFingerprint
millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 2502408 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.PeerSync We are already in sync. No need to
do a PeerSync
[junit4] 2> 2502408 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2502408 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 2502409 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2502409 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy PeerSync stage of recovery
was successful.
[junit4] 2> 2502409 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Replaying updates buffered
during PeerSync.
[junit4] 2> 2502409 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 2502409 INFO
(recoveryExecutor-1955-thread-1-processing-n:127.0.0.1:46492_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:46492_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Registering as Active
after recovery.
[junit4] 2> 2502421 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 2502422 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 2502422 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d00075b
[junit4] 2> 2502423 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@757f193f
[junit4] 2> 2502435 WARN
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] 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> 2502474 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2502474 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2502475 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: if uncommited for 15000ms;
[junit4] 2> 2502475 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2502475 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=8763921,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 2502476 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@13d1772b[collection1] main]
[junit4] 2> 2502477 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2502478 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2502478 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 2502479 INFO
(searcherExecutor-6951-thread-1-processing-n:127.0.0.1:45222_ x:collection1
c:collection1) [n:127.0.0.1:45222_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@13d1772b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2502479 INFO
(coreLoadExecutor-6950-thread-1-processing-n:127.0.0.1:45222_)
[n:127.0.0.1:45222_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1560594366620237824
[junit4] 2> 2502488 INFO
(coreZkRegister-6945-thread-1-processing-n:127.0.0.1:45222_ x:collection1
c:collection1) [n:127.0.0.1:45222_ c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 2502490 INFO
(updateExecutor-1961-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 2502490 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 2502490 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 2502490 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 2502490 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2502490 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core
[collection1] as recovering, leader is [http://127.0.0.1:50432/collection1/]
and I am [http://127.0.0.1:45222/collection1/]
[junit4] 2> 2502515 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:50432]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:45222_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 2502520 INFO (qtp1736091904-17595) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 2502520 INFO (qtp1736091904-17595) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 2502520 INFO (qtp1736091904-17595) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:45222_, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:45222","node_name":"127.0.0.1:45222_","state":"down"}
[junit4] 2> 2503048 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001/cores/collection1
[junit4] 2> 2503049 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001
[junit4] 2> 2503053 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2503053 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4ac995fb{/,null,AVAILABLE}
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@229f1efa{HTTP/1.1,[http/1.1]}{127.0.0.1:55947}
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.e.j.s.Server Started @2506300ms
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/tempDir-001/jetty4,
solrconfig=solrconfig.xml, hostContext=/, hostPort=55947,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001/cores}
[junit4] 2> 2503054 ERROR
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2503054 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-28T16:19:26.349Z
[junit4] 2> 2503064 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2503064 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001/solr.xml
[junit4] 2> 2503069 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2503069 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49483/solr
[junit4] 2> 2503078 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:55947_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 2503082 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:55947_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2503083 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:55947_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55947_
[junit4] 2> 2503085 INFO (zkCallback-1946-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2503085 INFO
(zkCallback-1964-thread-1-processing-n:127.0.0.1:45222_) [n:127.0.0.1:45222_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2503088 INFO
(zkCallback-1951-thread-1-processing-n:127.0.0.1:50432_) [n:127.0.0.1:50432_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2503088 INFO
(zkCallback-1957-thread-1-processing-n:127.0.0.1:46492_) [n:127.0.0.1:46492_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2503089 INFO
(zkCallback-1942-thread-1-processing-n:127.0.0.1:53370_) [n:127.0.0.1:53370_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2503093 INFO
(zkCallback-1971-thread-1-processing-n:127.0.0.1:55947_) [n:127.0.0.1:55947_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2503293 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:55947_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001/cores
[junit4] 2> 2503293 INFO
(TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[C54B093F53762F4F])
[n:127.0.0.1:55947_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2503301 INFO
(OverseerStateUpdate-97537147522056196-127.0.0.1:53370_-n_0000000000)
[n:127.0.0.1:53370_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2503524 INFO (qtp1736091904-17595) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:45222_, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:45222","node_name":"127.0.0.1:45222_","state":"recovering"}
[junit4] 2> 2503524 INFO (qtp1736091904-17595) [n:127.0.0.1:50432_ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 2503524 INFO (qtp1736091904-17595) [n:127.0.0.1:50432_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:45222_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1003
[junit4] 2> 2504024 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Attempting to PeerSync
from [http://127.0.0.1:50432/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 2504025 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.PeerSync PeerSync: core=collection1
url=http://127.0.0.1:45222 START replicas=[http://127.0.0.1:50432/collection1/]
nUpdates=100
[junit4] 2> 2504026 INFO (qtp1736091904-17598) [n:127.0.0.1:50432_
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 2504026 INFO (qtp1736091904-17598) [n:127.0.0.1:50432_
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp= path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2504027 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.IndexFingerprint IndexFingerprint
millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.PeerSync We are already in sync. No need to
do a PeerSync
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy PeerSync stage of recovery
was successful.
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Replaying updates buffered
during PeerSync.
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 2504028 INFO
(recoveryExecutor-1962-thread-1-processing-n:127.0.0.1:45222_ x:collection1
s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:45222_ c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Registering as Active
after recovery.
[junit4] 2> 2504318 WARN
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2504319 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 2504341 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2504418 WARN
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 2504419 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 2504459 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 2504460 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeyNothingIsSafeTest_C54B093F53762F4F-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 2504460 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d00075b
[junit4] 2> 2504463 INFO
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@1d6b2d56
[junit4] 2> 2504476 WARN
(coreLoadExecutor-6961-thread-1-processing-n:127.0.0.1:55947_)
[n:127.0.0.1:55947_ c:collection1 x:collection1] 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> 2504512 INFO (coreLoadExecu
[...truncated too long message...]
testApplyPartialUpdatesDependingOnNonAddShouldThrowException
[junit4] 2> 4115621 INFO
(TEST-UpdateLogTest.testApplyPartialUpdatesAfterMultipleCommits-seed#[C54B093F53762F4F])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testApplyPartialUpdatesAfterMultipleCommits
[junit4] 2> 4115621 INFO
(TEST-UpdateLogTest.testApplyPartialUpdatesAfterMultipleCommits-seed#[C54B093F53762F4F])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testApplyPartialUpdatesAfterMultipleCommits
[junit4] 2> 4115622 INFO
(SUITE-UpdateLogTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 4115622 INFO
(SUITE-UpdateLogTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2027570987
[junit4] 2> 4115622 INFO (coreCloseExecutor-15336-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@12fbcded
[junit4] 2> 4115622 INFO (coreCloseExecutor-15336-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 4115623 INFO (coreCloseExecutor-15336-thread-1) [
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@65e43f74
[junit4] 2> 4115707 INFO (coreCloseExecutor-15336-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 4115714 INFO
(SUITE-UpdateLogTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 4115714 INFO
(SUITE-UpdateLogTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for all SolrIndexSearchers to be released
[junit4] 2> 4115714 INFO
(SUITE-UpdateLogTest-seed#[C54B093F53762F4F]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {},
docValues:{}, maxPointsInLeafNode=1048, maxMBSortInHeap=6.638016119347386,
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=fr-BE,
timezone=Etc/GMT+8
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=266826832,total=531103744
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterFSTTest,
SolrIndexConfigTest, GraphQueryTest, TestMacros, TestReqParamsAPI,
SpellingQueryConverterTest, TestLegacyFieldCache, TestFiltering,
SolrCloudExampleTest, DistribDocExpirationUpdateProcessorTest,
CollectionTooManyReplicasTest, DistributedExpandComponentTest,
TestReloadAndDeleteDocs, DistanceFunctionTest,
DeleteLastCustomShardedReplicaTest, SolrSlf4jReporterTest,
TestLuceneMatchVersion, TestWordDelimiterFilterFactory, CopyFieldTest,
CollectionsAPIDistributedZkTest, TestClassicSimilarityFactory, TestQueryUtils,
StressHdfsTest, DistributedTermsComponentTest, TestMaxScoreQueryParser,
TestDistributedGrouping, SolrIndexMetricsTest, RulesTest,
RAMDirectoryFactoryTest, SliceStateTest, TestShortCircuitedRequests,
DocValuesTest, SpatialFilterTest, TestConfigSetsAPIZkFailure,
TestSolrCloudWithKerberosAlt, TestSystemIdResolver, ZkStateWriterTest,
HdfsTlogReplayBufferedWhileIndexingTest, SoftAutoCommitTest,
ConvertedLegacyTest, SearchHandlerTest, DirectSolrSpellCheckerTest,
VMParamsZkACLAndCredentialsProvidersTest, DeleteShardTest,
RemoteQueryErrorTest, TestElisionMultitermQuery, TestBM25SimilarityFactory,
BlockDirectoryTest, DistributedFacetPivotLongTailTest,
ConcurrentDeleteAndCreateCollectionTest, TestRTGBase, HttpPartitionTest,
EnumFieldTest, SolrIndexSplitterTest, SystemInfoHandlerTest,
HdfsNNFailoverTest, TestBackupRepositoryFactory, ActionThrottleTest,
ShardRoutingTest, TestCoreContainer, CoreAdminRequestStatusTest,
SaslZkACLProviderTest, SimplePostToolTest, TestClassNameShortening,
EchoParamsTest, DistributedIntervalFacetingTest, BlockJoinFacetDistribTest,
OpenCloseCoreStressTest, TestCustomSort, TestOrdValues,
TestSolrQueryParserResource, ClusterStateUpdateTest,
DistributedFacetPivotWhiteBoxTest, BasicFunctionalityTest, IndexSchemaTest,
JavabinLoaderTest, SampleTest, AsyncCallRequestStatusResponseTest,
TestInfoStreamLogging, TestQueryTypes, DistributedVersionInfoTest,
TestSubQueryTransformerCrossCore, IndexSchemaRuntimeFieldTest,
DistributedSuggestComponentTest, TestPKIAuthenticationPlugin,
TestOverriddenPrefixQueryForCustomFieldType, TestTrie,
TestCursorMarkWithoutUniqueKey, OverseerModifyCollectionTest,
ShardRoutingCustomTest, TestSolrFieldCacheMBean,
ClassificationUpdateProcessorTest, DirectSolrConnectionTest, ZkSolrClientTest,
TestExpandComponent, RestartWhileUpdatingTest,
IgnoreCommitOptimizeUpdateProcessorFactoryTest, AnalyticsQueryTest,
JsonLoaderTest, HdfsRestartWhileUpdatingTest,
TestTolerantUpdateProcessorRandomCloud, RankQueryTest, TestDefaultStatsCache,
TestLeaderInitiatedRecoveryThread, TestFuzzyAnalyzedSuggestions,
TestMiniSolrCloudCluster, HighlighterTest, CreateCollectionCleanupTest,
DistributedMLTComponentTest, SharedFSAutoReplicaFailoverUtilsTest,
TestSolrDynamicMBean, TestFieldResource, TestOnReconnectListenerSupport,
TestSystemCollAutoCreate, TestPerFieldSimilarityClassic,
CdcrVersionReplicationTest, AnalysisAfterCoreReloadTest,
SpellCheckCollatorTest, TestFoldingMultitermQuery, SuggesterTest,
WordBreakSolrSpellCheckerTest, TestPseudoReturnFields,
FieldMutatingUpdateProcessorTest, SortByFunctionTest, TestSolrDeletionPolicy1,
SolrInfoMBeanTest, CacheHeaderTest, TestSurroundQueryParser,
DisMaxRequestHandlerTest, DocumentAnalysisRequestHandlerTest,
FileBasedSpellCheckerTest, TermsComponentTest, TestValueSourceCache,
TestIndexingPerformance, TestSolrQueryParser, LoggingHandlerTest,
RegexBoostProcessorTest, ReturnFieldsTest, TestCSVResponseWriter,
UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter,
CSVRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache,
TestSimpleTrackingShardHandler, AliasIntegrationTest,
CdcrReplicationDistributedZkTest, CdcrRequestHandlerTest,
CollectionsAPISolrJTest, MigrateRouteKeyTest, ShardSplitTest,
TestStressInPlaceUpdates, TestStressLiveNodes, HdfsBasicDistributedZk2Test,
HdfsBasicDistributedZkTest, HdfsUnloadDistributedZkTest, ZkStateReaderTest,
DirectoryFactoryTest, ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest,
TestConfigOverlay, TestConfigSetImmutable, TestConfigSetProperties,
TestCustomStream, TestImplicitCoreProperties, TestInitParams,
TestShardHandlerFactory, TestSolrConfigHandler, TestSolrCoreSnapshots,
RequestLoggingTest, TestConfigReload, TestSQLHandler, V2ApiIntegrationTest,
CoreAdminCreateDiscoverTest, MetricsHandlerTest,
SegmentsInfoRequestHandlerTest, StatsReloadRaceTest, TestCollectionAPIs,
TestConfigsApi, DistributedDebugComponentTest, DistributedFacetPivotLargeTest,
DistributedFacetPivotSmallAdvancedTest,
DistributedQueryComponentCustomSortTest, FacetPivotSmallTest,
ReplicaListTransformerTest, SpatialHeatmapFacetsTest, TestPivotHelperCode,
TestTrackingShardHandlerFactory, HighlighterMaxOffsetTest,
TestUnifiedSolrHighlighter, TestSlowCompositeReaderWrapper,
WrapperMergePolicyFactoryTest, CheckHdfsIndexTest, SolrCoreMetricManagerTest,
SolrMetricManagerTest, SolrMetricReporterTest, SolrGangliaReporterTest,
SolrGraphiteReporterTest, SolrJmxReporterTest, SmileWriterTest,
SubstringBytesRefFilterTest, TestMacroExpander, JSONWriterTest,
SmileWriterTest, TestBinaryResponseWriter, TestExportWriter,
TestGeoJSONResponseWriter, TestJavabinTupleStreamParser, TestPushWriter,
TestRawResponseWriter, TestSolrQueryResponse, TestSubQueryTransformer,
TestSubQueryTransformerDistrib, TestDynamicFieldCollectionResource,
TestDynamicFieldResource, TestFieldTypeCollectionResource,
TestSchemaNameResource, TestSolrQueryParserDefaultOperatorResource,
TestManagedSynonymFilterFactory, ManagedSchemaRoundRobinCloudTest,
TestBulkSchemaConcurrent, CursorMarkTest, MergeStrategyTest,
TestFieldSortValues, TestGraphTermsQParserPlugin, TestReloadDeadlock,
TestExactStatsCache, TestDelegationWithHadoopAuth, HttpSolrCallGetCoreTest,
ResponseHeaderTest, TestAnalyzeInfixSuggestions, TestFreeTextSuggestions,
TestDocTermOrds, TestFieldCacheReopen, TestFieldCacheSanityChecker,
TestFieldCacheSortRandom, TestUninvertingReader, CdcrUpdateLogTest,
UpdateLogTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=UpdateLogTest
-Dtests.seed=C54B093F53762F4F -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/test-data/enwiki.random.lines.txt
-Dtests.locale=fr-BE -Dtests.timezone=Etc/GMT+8 -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | UpdateLogTest (suite) <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Clean up
static fields (in @AfterClass?), your test seems to hang on to approximately
20,874,344 bytes (threshold is 10,485,760). Field reference sizes (counted
individually):
[junit4] > - 20,874,016 bytes, static org.apache.solr.update.UpdateLog
org.apache.solr.update.UpdateLogTest.ulog
[junit4] > - 192 bytes, public static org.junit.rules.TestRule
org.apache.solr.SolrTestCaseJ4.solrClassRules
[junit4] > - 128 bytes, private static java.lang.String
org.apache.solr.SolrTestCaseJ4.factoryProp
[junit4] > - 64 bytes, private static java.lang.String
org.apache.solr.SolrTestCaseJ4.coreName
[junit4] > - 48 bytes, private static java.util.Map
org.apache.solr.SolrTestCaseJ4.savedClassLogLevels
[junit4] > at
__randomizedtesting.SeedInfo.seed([C54B093F53762F4F]:0)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [672/696 (2!)] on J1 in 0.95s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 62754 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
