Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/558/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED:
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest.testRandomNRT
Error Message:
Captured an uncaught exception in thread: Thread[id=29, name=Thread-1,
state=RUNNABLE, group=TGRP-AnalyzingInfixSuggesterTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=29, name=Thread-1, state=RUNNABLE,
group=TGRP-AnalyzingInfixSuggesterTest]
at
__randomizedtesting.SeedInfo.seed([570DDDB64D691936:F323D30B15B6C58A]:0)
Caused by: org.apache.lucene.store.AlreadyClosedException: this
ReferenceManager is closed
at __randomizedtesting.SeedInfo.seed([570DDDB64D691936]:0)
at
org.apache.lucene.search.ReferenceManager.acquire(ReferenceManager.java:98)
at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggester.lookup(AnalyzingInfixSuggester.java:645)
at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggester.lookup(AnalyzingInfixSuggester.java:457)
at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest$LookupThread.run(AnalyzingInfixSuggesterTest.java:533)
FAILED: org.apache.solr.cloud.ShardSplitTest.test
Error Message:
Wrong doc count on shard1_0. See SOLR-5309 expected:<128> but was:<127>
Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_0. See SOLR-5309
expected:<128> but was:<127>
at
__randomizedtesting.SeedInfo.seed([582147CA013F4EB0:D0757810AFC32348]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:886)
at
org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:669)
at org.apache.solr.cloud.ShardSplitTest.test(ShardSplitTest.java:101)
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:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9312 lines...]
[junit4] Suite:
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest
[junit4] 2> dec. 06, 2016 12:18:03 AM
com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler
uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread:
Thread[Thread-1,5,TGRP-AnalyzingInfixSuggesterTest]
[junit4] 2> org.apache.lucene.store.AlreadyClosedException: this
ReferenceManager is closed
[junit4] 2> at
__randomizedtesting.SeedInfo.seed([570DDDB64D691936]:0)
[junit4] 2> at
org.apache.lucene.search.ReferenceManager.acquire(ReferenceManager.java:98)
[junit4] 2> at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggester.lookup(AnalyzingInfixSuggester.java:645)
[junit4] 2> at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggester.lookup(AnalyzingInfixSuggester.java:457)
[junit4] 2> at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest$LookupThread.run(AnalyzingInfixSuggesterTest.java:533)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=AnalyzingInfixSuggesterTest -Dtests.method=testRandomNRT
-Dtests.seed=570DDDB64D691936 -Dtests.slow=true -Dtests.locale=da-DK
-Dtests.timezone=NZ-CHAT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 22.4s J0 | AnalyzingInfixSuggesterTest.testRandomNRT <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=29, name=Thread-1, state=RUNNABLE,
group=TGRP-AnalyzingInfixSuggesterTest]
[junit4] > at
__randomizedtesting.SeedInfo.seed([570DDDB64D691936:F323D30B15B6C58A]:0)
[junit4] > Caused by: org.apache.lucene.store.AlreadyClosedException:
this ReferenceManager is closed
[junit4] > at
__randomizedtesting.SeedInfo.seed([570DDDB64D691936]:0)
[junit4] > at
org.apache.lucene.search.ReferenceManager.acquire(ReferenceManager.java:98)
[junit4] > at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggester.lookup(AnalyzingInfixSuggester.java:645)
[junit4] > at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggester.lookup(AnalyzingInfixSuggester.java:457)
[junit4] > at
org.apache.lucene.search.suggest.analyzing.AnalyzingInfixSuggesterTest$LookupThread.run(AnalyzingInfixSuggesterTest.java:533)
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=da-DK,
timezone=NZ-CHAT
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102
(64-bit)/cpus=3,threads=1,free=172227408,total=503316480
[junit4] 2> NOTE: All tests run in this JVM: [FileDictionaryTest,
TestWordBreakSpellChecker, DocumentDictionaryTest, TestLevenshteinDistance,
TestFuzzyCompletionQuery, TestContextQuery, BytesRefSortersTest,
TestJaroWinklerDistance, AnalyzingInfixSuggesterTest]
[junit4] Completed [31/32 (1!)] on J0 in 24.01s, 22 tests, 1 error <<<
FAILURES!
[...truncated 2148 lines...]
[junit4] JVM J1: stdout was not empty, see:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/temp/junit4-J1-20161205_103408_401.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # Internal Error (sharedRuntime.cpp:873), pid=11572,
tid=0x0000000000005c2b
[junit4] # guarantee(nm != NULL) failed: must have containing nmethod for
implicit division-by-zero exceptions
[junit4] #
[junit4] # JRE version: Java(TM) SE Runtime Environment (8.0_102-b14) (build
1.8.0_102-b14)
[junit4] # Java VM: Java HotSpot(TM) 64-Bit Server VM (25.102-b14 mixed mode
bsd-amd64 )
[junit4] # Failed to write core dump. Core dumps have been disabled. To
enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] #
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/hs_err_pid11572.log
[junit4] #
[junit4] # If you would like to submit a bug report, please visit:
[junit4] # http://bugreport.java.com/bugreport/crash.jsp
[junit4] #
[junit4] <<< JVM J1: EOF ----
[...truncated 78 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/init-core-data-001
[junit4] 2> 1749364 INFO
(SUITE-ShardSplitTest-seed#[582147CA013F4EB0]-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)
w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1749364 INFO
(SUITE-ShardSplitTest-seed#[582147CA013F4EB0]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /m/f
[junit4] 2> 1749365 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1749366 INFO (Thread-2377) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1749366 INFO (Thread-2377) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1749466 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkTestServer start zk server on port:60231
[junit4] 2> 1749708 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1749712 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 1749716 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1749719 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1749722 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1749725 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1749728 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1749731 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1749733 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1749736 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1749739 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1750077 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/control-001/cores/collection1
[junit4] 2> 1750079 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1750081 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@32e36e07{/m/f,null,AVAILABLE}
[junit4] 2> 1750083 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2a205ef3{HTTP/1.1,[http/1.1]}{127.0.0.1:60234}
[junit4] 2> 1750083 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server Started @1755911ms
[junit4] 2> 1750084 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/tempDir-001/control/data,
hostContext=/m/f, hostPort=60234,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/control-001/cores}
[junit4] 2> 1750084 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1750084 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 1750084 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1750084 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1750084 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-05T11:03:24.382Z
[junit4] 2> 1750088 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1750088 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/control-001/solr.xml
[junit4] 2> 1750098 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1750099 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60231/solr
[junit4] 2> 1750141 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:60234_m%2Ff
[junit4] 2> 1750143 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.Overseer Overseer
(id=97054608829054980-127.0.0.1:60234_m%2Ff-n_0000000000) starting
[junit4] 2> 1750156 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60234_m%2Ff
[junit4] 2> 1750158 INFO
(zkCallback-2043-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1750187 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/control-001/cores
[junit4] 2> 1750188 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1750192 INFO
(OverseerStateUpdate-97054608829054980-127.0.0.1:60234_m%2Ff-n_0000000000)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 1751214 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1751226 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1751337 WARN
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff 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> 1751340 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1751369 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 1751369 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/control-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/control-001/cores/collection1/data/]
[junit4] 2> 1751369 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ba598c9
[junit4] 2> 1751373 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=46.8974609375,
floorSegmentMB=1.6220703125, forceMergeDeletesPctAllowed=16.70074081056602,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1751383 WARN
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1751393 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1751393 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 1751395 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1751395 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1751395 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=24.17578125,
floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=10.252172632730833,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1751396 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@45f94fdb[collection1] main]
[junit4] 2> 1751398 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 1751399 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1751399 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1751400 INFO
(searcherExecutor-4714-thread-1-processing-n:127.0.0.1:60234_m%2Ff
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@45f94fdb[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1751400 INFO
(coreLoadExecutor-4713-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1552873743395586048
[junit4] 2> 1751412 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1751412 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
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> 1751412 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:60234/m/f/collection1/
[junit4] 2> 1751412 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1751412 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:60234/m/f/collection1/ has no replicas
[junit4] 2> 1751420 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
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:60234/m/f/collection1/ shard1
[junit4] 2> 1751592 INFO
(coreZkRegister-4706-thread-1-processing-n:127.0.0.1:60234_m%2Ff x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60234_m%2Ff
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1751709 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1751711 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60231/solr ready
[junit4] 2> 1751711 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1751711 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 1752032 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001/cores/collection1
[junit4] 2> 1752033 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001
[junit4] 2> 1752034 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1752037 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@73d9fbb0{/m/f,null,AVAILABLE}
[junit4] 2> 1752038 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5083b23d{HTTP/1.1,[http/1.1]}{127.0.0.1:60240}
[junit4] 2> 1752038 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server Started @1757866ms
[junit4] 2> 1752038 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/m/f, hostPort=60240,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001/cores}
[junit4] 2> 1752039 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1752039 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 1752039 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1752039 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1752039 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-05T11:03:26.337Z
[junit4] 2> 1752044 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1752044 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001/solr.xml
[junit4] 2> 1752072 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1752073 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60231/solr
[junit4] 2> 1752091 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1752101 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60240_m%2Ff
[junit4] 2> 1752104 INFO (zkCallback-2047-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1752105 INFO
(zkCallback-2043-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1752105 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1752217 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001/cores
[junit4] 2> 1752218 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1752221 INFO
(OverseerStateUpdate-97054608829054980-127.0.0.1:60234_m%2Ff-n_0000000000)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 1752325 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1753236 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1753249 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1753338 WARN
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff 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> 1753340 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1753373 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1753374 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 1753374 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ba598c9
[junit4] 2> 1753377 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=46.8974609375,
floorSegmentMB=1.6220703125, forceMergeDeletesPctAllowed=16.70074081056602,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1753386 WARN
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1753405 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1753405 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1753407 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1753408 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1753409 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=24.17578125,
floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=10.252172632730833,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1753409 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7bf88afd[collection1] main]
[junit4] 2> 1753411 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1753412 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1753412 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1753414 INFO
(searcherExecutor-4725-thread-1-processing-n:127.0.0.1:60240_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@7bf88afd[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1753414 INFO
(coreLoadExecutor-4724-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1552873745507418112
[junit4] 2> 1753427 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 1753428 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 1753428 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:60240/m/f/collection1/
[junit4] 2> 1753428 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 1753428 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:60240/m/f/collection1/ has no replicas
[junit4] 2> 1753436 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:60240/m/f/collection1/ shard2
[junit4] 2> 1753548 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1753606 INFO
(coreZkRegister-4719-thread-1-processing-n:127.0.0.1:60240_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60240_m%2Ff c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 1753714 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 1754044 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001/cores/collection1
[junit4] 2> 1754045 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001
[junit4] 2> 1754046 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1754048 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@70d82a2d{/m/f,null,AVAILABLE}
[junit4] 2> 1754049 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@9ffa6c6{HTTP/1.1,[http/1.1]}{127.0.0.1:60244}
[junit4] 2> 1754049 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server Started @1759877ms
[junit4] 2> 1754049 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/m/f, hostPort=60244,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001/cores}
[junit4] 2> 1754049 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1754050 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 1754050 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1754050 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1754050 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-05T11:03:28.348Z
[junit4] 2> 1754055 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1754055 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001/solr.xml
[junit4] 2> 1754091 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1754092 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60231/solr
[junit4] 2> 1754115 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1754129 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60244_m%2Ff
[junit4] 2> 1754132 INFO
(zkCallback-2043-thread-3-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1754132 INFO (zkCallback-2047-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1754133 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1754132 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1754191 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001/cores
[junit4] 2> 1754191 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1754196 INFO
(OverseerStateUpdate-97054608829054980-127.0.0.1:60234_m%2Ff-n_0000000000)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 1754302 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1754302 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1755219 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1755282 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1755381 WARN
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff 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> 1755383 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1755421 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1755422 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 1755422 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ba598c9
[junit4] 2> 1755428 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=46.8974609375,
floorSegmentMB=1.6220703125, forceMergeDeletesPctAllowed=16.70074081056602,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1755446 WARN
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1755502 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1755502 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1755504 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1755505 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1755506 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=24.17578125,
floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=10.252172632730833,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1755508 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2f90c722[collection1] main]
[junit4] 2> 1755510 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1755511 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1755512 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1755513 INFO
(searcherExecutor-4736-thread-1-processing-n:127.0.0.1:60244_m%2Ff
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@2f90c722[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1755513 INFO
(coreLoadExecutor-4735-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1552873747708379136
[junit4] 2> 1755525 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 1755525 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 1755526 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:60244/m/f/collection1/
[junit4] 2> 1755526 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 1755526 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:60244/m/f/collection1/ has no replicas
[junit4] 2> 1755534 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:60244/m/f/collection1/ shard1
[junit4] 2> 1755640 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1755641 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1755690 INFO
(coreZkRegister-4730-thread-1-processing-n:127.0.0.1:60244_m%2Ff x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:60244_m%2Ff c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 1755816 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1755816 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1756301 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001/cores/collection1
[junit4] 2> 1756304 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001
[junit4] 2> 1756305 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1756312 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@926556a{/m/f,null,AVAILABLE}
[junit4] 2> 1756312 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3a61fb52{HTTP/1.1,[http/1.1]}{127.0.0.1:60248}
[junit4] 2> 1756312 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server Started @1762140ms
[junit4] 2> 1756312 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/m/f, hostPort=60248,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001/cores}
[junit4] 2> 1756313 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1756314 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 1756314 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1756315 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1756315 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-05T11:03:30.613Z
[junit4] 2> 1756321 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1756322 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001/solr.xml
[junit4] 2> 1756345 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1756346 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60231/solr
[junit4] 2> 1756373 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 1756385 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60248_m%2Ff
[junit4] 2> 1756388 INFO
(zkCallback-2043-thread-1-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1756388 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1756388 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1756390 INFO
(zkCallback-2065-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 1756390 INFO (zkCallback-2047-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1756436 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001/cores
[junit4] 2> 1756436 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1756443 INFO
(OverseerStateUpdate-97054608829054980-127.0.0.1:60234_m%2Ff-n_0000000000)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 1756552 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1756553 INFO
(zkCallback-2065-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1756553 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1757466 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1757485 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1757628 WARN
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff 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> 1757630 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1757670 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1757670 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 1757670 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ba598c9
[junit4] 2> 1757676 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=40, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=46.8974609375,
floorSegmentMB=1.6220703125, forceMergeDeletesPctAllowed=16.70074081056602,
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1757691 WARN
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1757712 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1757712 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1757714 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1757715 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1757716 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=22, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=24.17578125,
floorSegmentMB=1.693359375, forceMergeDeletesPctAllowed=10.252172632730833,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1757717 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@66198b7d[collection1] main]
[junit4] 2> 1757719 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1757720 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1757720 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1757721 INFO
(searcherExecutor-4747-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@66198b7d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1757722 INFO
(coreLoadExecutor-4746-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1552873750024683520
[junit4] 2> 1757731 INFO
(coreZkRegister-4741-thread-1-processing-n:127.0.0.1:60248_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 1757732 INFO
(updateExecutor-2062-thread-1-processing-n:127.0.0.1:60248_m%2Ff x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running
recovery
[junit4] 2> 1757732 INFO
(recoveryExecutor-2063-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1757733 INFO
(recoveryExecutor-2063-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
###### startupVersions=[[]]
[junit4] 2> 1757733 INFO
(recoveryExecutor-2063-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Begin buffering updates. core=[collection1]
[junit4] 2> 1757733 INFO
(recoveryExecutor-2063-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting
to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1757733 INFO
(recoveryExecutor-2063-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Publishing state of core [collection1] as recovering, leader is
[http://127.0.0.1:60240/m/f/collection1/] and I am
[http://127.0.0.1:60248/m/f/collection1/]
[junit4] 2> 1757738 INFO
(recoveryExecutor-2063-thread-1-processing-n:127.0.0.1:60248_m%2Ff
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:60248_m%2Ff
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Sending prep recovery command to [http://127.0.0.1:60240/m/f]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:60248_m%252Ff&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1757742 INFO (qtp904458019-10894) [n:127.0.0.1:60240_m%2Ff
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1757744 INFO (qtp904458019-10894) [n:127.0.0.1:60240_m%2Ff
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard2 of collection1) have state: recovering
[junit4] 2> 1757744 INFO (qtp904458019-10894) [n:127.0.0.1:60240_m%2Ff
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:60248_m%2Ff, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:60248/m/f","node_name":"127.0.0.1:60248_m%2Ff","state":"down"}
[junit4] 2> 1757847 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1757847 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1757847 INFO
(zkCallback-2065-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 1758485 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001/cores/collection1
[junit4] 2> 1758487 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001
[junit4] 2> 1758488 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1758494 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@57a618cb{/m/f,null,AVAILABLE}
[junit4] 2> 1758495 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@38226970{HTTP/1.1,[http/1.1]}{127.0.0.1:60253}
[junit4] 2> 1758495 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.e.j.s.Server Started @1764323ms
[junit4] 2> 1758495 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/tempDir-001/jetty4,
solrconfig=solrconfig.xml, hostContext=/m/f, hostPort=60253,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001/cores}
[junit4] 2> 1758497 ERROR
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1758498 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 1758498 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1758498 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1758498 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-05T11:03:32.796Z
[junit4] 2> 1758505 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1758505 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001/solr.xml
[junit4] 2> 1758523 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1758524 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60231/solr
[junit4] 2> 1758551 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60253_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 1758563 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60253_m%2Ff ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60253_m%2Ff
[junit4] 2> 1758567 INFO
(zkCallback-2065-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1758568 INFO
(zkCallback-2072-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1758568 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1758567 INFO
(zkCallback-2043-thread-2-processing-n:127.0.0.1:60234_m%2Ff)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1758568 INFO (zkCallback-2047-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1758568 INFO
(zkCallback-2053-thread-1-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 1758685 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1758685 INFO
(zkCallback-2065-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1758686 INFO
(zkCallback-2053-thread-2-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1758746 INFO (qtp904458019-10894) [n:127.0.0.1:60240_m%2Ff
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:60248_m%2Ff, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:60248/m/f","node_name":"127.0.0.1:60248_m%2Ff","state":"recovering"}
[junit4] 2> 1758746 INFO (qtp904458019-10894) [n:127.0.0.1:60240_m%2Ff
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1758746 INFO (qtp904458019-10894) [n:127.0.0.1:60240_m%2Ff
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:60248_m%252Ff&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1004
[junit4] 2> 1759012 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60253_m%2Ff ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001/cores
[junit4] 2> 1759012 INFO
(TEST-ShardSplitTest.testSplitShardWithRule-seed#[582147CA013F4EB0])
[n:127.0.0.1:60253_m%2Ff ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1759016 INFO
(OverseerStateUpdate-97054608829054980-127.0.0.1:60234_m%2Ff-n_0000000000)
[n:127.0.0.1:60234_m%2Ff ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 1759123 INFO
(zkCallback-2059-thread-1-processing-n:127.0.0.1:60244_m%2Ff)
[n:127.0.0.1:60244_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1759123 INFO
(zkCallback-2053-thread-2-processing-n:127.0.0.1:60240_m%2Ff)
[n:127.0.0.1:60240_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1759123 INFO
(zkCallback-2072-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1759123 INFO
(zkCallback-2065-thread-1-processing-n:127.0.0.1:60248_m%2Ff)
[n:127.0.0.1:60248_m%2Ff ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 1760033 INFO
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 1760047 INFO
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1760142 WARN
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff 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> 1760143 INFO
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1760171 INFO
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1760172 INFO
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ShardSplitTest_582147CA013F4EB0-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 1760172 INFO
(coreLoadExecutor-4757-thread-1-processing-n:127.0.0.1:60253_m%2Ff)
[n:127.0.0.1:60253_m%2Ff c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.JmxMonitoredMap J
[...truncated too long message...]
cheds-core-avl-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-core-shared-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-i18n-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptor-kerberos-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/api-all-1.0.0-M20.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/bcprov-jdk15-1.45.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/cglib-nodep-2.2.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/commons-collections-3.2.2.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/commons-math3-3.4.1.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/easymock-3.0.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/ehcache-core-2.4.4.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/hadoop-common-2.7.2-tests.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/hadoop-hdfs-2.7.2-tests.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/hadoop-minikdc-2.7.2.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/jackson-annotations-2.5.4.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/jackson-databind-2.5.4.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/jersey-core-1.9.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/jersey-server-1.9.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/jetty-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/jetty-util-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/mina-core-2.0.0-M5.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/mockito-core-1.9.5.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/netty-all-4.0.36.Final.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/objenesis-1.2.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/presto-parser-0.122.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/core/test-lib/slice-0.10.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/lucene/build/analysis/icu/lucene-analyzers-icu-6.4.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/contrib/solr-analysis-extras/classes/java:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/lucene/build/queryparser/classes/test:/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/Users/jenkins/.ant/lib/ivy-2.3.0.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_102.jdk/Contents/Home/lib/tools.jar:/Users/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.4.0.jar
com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/temp/junit4-J1-20161205_103408_401.events
@/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/temp/junit4-J1-20161205_103408_401.suites
-stdin
[junit4] ERROR: JVM J1 ended with an exception: Forked process returned with
error code: 134. Very likely a JVM crash. See process stdout at:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/temp/junit4-J1-20161205_103408_401.sysout
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1481)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] at java.lang.Thread.run(Thread.java:745)
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:765: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:709: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:59: The following
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build.xml:252: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/common-build.xml:536: The
following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/lucene/common-build.xml:1443:
The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/lucene/common-build.xml:984: At
least one slave process threw an exception, first: Forked process returned with
error code: 134. Very likely a JVM crash. See process stdout at:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/temp/junit4-J1-20161205_103408_401.sysout
Total time: 111 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
