Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/832/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test
Error Message:
Could not find collection:collection2
Stack Trace:
java.lang.AssertionError: Could not find collection:collection2
at
__randomizedtesting.SeedInfo.seed([7ADD612BFEF1374E:F2895EF1500D5AB6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:159)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:144)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:139)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:620)
at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11460 lines...]
[junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/init-core-data-001
[junit4] 2> 932330 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[7ADD612BFEF1374E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 932331 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[7ADD612BFEF1374E]-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> 932331 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[7ADD612BFEF1374E]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /a/r
[junit4] 2> 932333 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 932334 INFO (Thread-1150) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 932334 INFO (Thread-1150) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 932443 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkTestServer start zk server on port:51312
[junit4] 2> 932489 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932525 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932570 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932573 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932576 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932581 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932583 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932586 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932588 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932591 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 932593 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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> 935614 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/control-001/cores/collection1
[junit4] 2> 935617 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 935619 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@695d0050{/a/r,null,AVAILABLE}
[junit4] 2> 935623 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1d3d46dc{HTTP/1.1,[http/1.1]}{127.0.0.1:51315}
[junit4] 2> 935623 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server Started @942445ms
[junit4] 2> 935623 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/tempDir-001/control/data,
hostContext=/a/r, hostPort=51315,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/control-001/cores}
[junit4] 2> 935624 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 935624 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 935624 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 935624 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 935624 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-23T04:12:59.901Z
[junit4] 2> 935628 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 935628 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/control-001/solr.xml
[junit4] 2> 935639 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 935640 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51312/solr
[junit4] 2> 935688 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 935689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:51315_a%2Fr
[junit4] 2> 935691 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.Overseer Overseer
(id=97840056016109572-127.0.0.1:51315_a%2Fr-n_0000000000) starting
[junit4] 2> 935705 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51315_a%2Fr
[junit4] 2> 935708 INFO
(zkCallback-911-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 935816 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/control-001/cores
[junit4] 2> 935816 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 935818 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 935826 INFO
(OverseerStateUpdate-97840056016109572-127.0.0.1:51315_a%2Fr-n_0000000000)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 936851 WARN
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 936851 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 936893 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 936954 WARN
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr 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> 936955 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 936984 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection, trusted=true
[junit4] 2> 936984 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 936984 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection 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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/control-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/control-001/cores/collection1/data/]
[junit4] 2> 936985 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b023dc
[junit4] 2> 936986 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.310546875,
floorSegmentMB=1.8974609375, forceMergeDeletesPctAllowed=1.49240552874922,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 936991 WARN
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 937075 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 937075 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 937078 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 937078 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 937079 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=44, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=93.7666015625,
floorSegmentMB=2.0625, forceMergeDeletesPctAllowed=6.277220002587455,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8751709416621902
[junit4] 2> 937079 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3fe3300d[collection1] main]
[junit4] 2> 937081 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 937082 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 937082 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 937083 INFO
(searcherExecutor-2555-thread-1-processing-n:127.0.0.1:51315_a%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:51315_a%2Fr
c:control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registered
new searcher Searcher@3fe3300d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 937083 INFO
(coreLoadExecutor-2554-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr c:control_collection x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1565440901235343360
[junit4] 2> 937095 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 937095 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr 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> 937095 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:51315/a/r/collection1/
[junit4] 2> 937095 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 937095 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:51315/a/r/collection1/ has no replicas
[junit4] 2> 937095 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 937102 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr 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:51315/a/r/collection1/ shard1
[junit4] 2> 937114 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 937117 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51312/solr ready
[junit4] 2> 937117 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 937117 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 937158 INFO
(coreZkRegister-2547-thread-1-processing-n:127.0.0.1:51315_a%2Fr x:collection1
c:control_collection) [n:127.0.0.1:51315_a%2Fr c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 937692 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001/cores/collection1
[junit4] 2> 937693 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001
[junit4] 2> 937695 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 937697 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@414393e3{/a/r,null,AVAILABLE}
[junit4] 2> 937698 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3fae6a04{HTTP/1.1,[http/1.1]}{127.0.0.1:51321}
[junit4] 2> 937698 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server Started @944520ms
[junit4] 2> 937698 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/a/r, hostPort=51321,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001/cores}
[junit4] 2> 937699 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 937699 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 937699 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 937699 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 937699 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-23T04:13:01.976Z
[junit4] 2> 937703 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 937703 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001/solr.xml
[junit4] 2> 937714 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 937715 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51312/solr
[junit4] 2> 937731 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 937738 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 937742 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51321_a%2Fr
[junit4] 2> 937745 INFO
(zkCallback-911-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 937745 INFO (zkCallback-915-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 937746 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 937939 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001/cores
[junit4] 2> 937939 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 937942 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 937944 INFO
(OverseerStateUpdate-97840056016109572-127.0.0.1:51315_a%2Fr-n_0000000000)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 938048 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 938971 WARN
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 938971 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.0
[junit4] 2> 938984 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 939067 WARN
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr 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> 939068 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 939095 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 939096 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 939096 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 939096 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b023dc
[junit4] 2> 939097 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.310546875,
floorSegmentMB=1.8974609375, forceMergeDeletesPctAllowed=1.49240552874922,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 939103 WARN
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 939172 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 939172 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 939176 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 939176 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 939177 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=44, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=93.7666015625,
floorSegmentMB=2.0625, forceMergeDeletesPctAllowed=6.277220002587455,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8751709416621902
[junit4] 2> 939177 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@43ea872e[collection1] main]
[junit4] 2> 939179 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 939180 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 939180 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 939181 INFO
(searcherExecutor-2566-thread-1-processing-n:127.0.0.1:51321_a%2Fr
x:collection1 c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@43ea872e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 939181 INFO
(coreLoadExecutor-2565-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1565440903435255808
[junit4] 2> 939192 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 939192 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr 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> 939192 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:51321/a/r/collection1/
[junit4] 2> 939192 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 939192 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51321/a/r/collection1/ has
no replicas
[junit4] 2> 939192 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 939203 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51321/a/r/collection1/ shard2
[junit4] 2> 939313 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 939315 INFO
(coreZkRegister-2560-thread-1-processing-n:127.0.0.1:51321_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51321_a%2Fr c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 939429 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 940028 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001/cores/collection1
[junit4] 2> 940032 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001
[junit4] 2> 940033 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 940035 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2beeaa4d{/a/r,null,AVAILABLE}
[junit4] 2> 940036 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6713d475{HTTP/1.1,[http/1.1]}{127.0.0.1:51325}
[junit4] 2> 940036 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server Started @946857ms
[junit4] 2> 940036 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/a/r, hostPort=51325,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001/cores}
[junit4] 2> 940037 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 940037 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 940037 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 940037 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 940037 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-23T04:13:04.314Z
[junit4] 2> 940043 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 940043 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001/solr.xml
[junit4] 2> 940056 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 940057 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51312/solr
[junit4] 2> 940062 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15b990337a5000b, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 940079 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 940085 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 940089 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51325_a%2Fr
[junit4] 2> 940093 INFO (zkCallback-915-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 940093 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 940093 INFO
(zkCallback-911-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 940094 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 940223 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001/cores
[junit4] 2> 940223 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 940230 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 940234 INFO
(OverseerStateUpdate-97840056016109572-127.0.0.1:51315_a%2Fr-n_0000000000)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard3
[junit4] 2> 940345 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 940345 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 941271 WARN
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 941272 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.0
[junit4] 2> 941292 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 941408 WARN
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr 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> 941410 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 941453 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 941453 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 941453 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 941454 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b023dc
[junit4] 2> 941457 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.310546875,
floorSegmentMB=1.8974609375, forceMergeDeletesPctAllowed=1.49240552874922,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 941464 WARN
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 941563 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 941563 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 941565 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 941565 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 941567 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=44, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=93.7666015625,
floorSegmentMB=2.0625, forceMergeDeletesPctAllowed=6.277220002587455,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8751709416621902
[junit4] 2> 941567 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@489d9cf9[collection1] main]
[junit4] 2> 941569 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 941570 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 941570 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 941571 INFO
(searcherExecutor-2577-thread-1-processing-n:127.0.0.1:51325_a%2Fr
x:collection1 c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@489d9cf9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 941572 INFO
(coreLoadExecutor-2576-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1565440905942401024
[junit4] 2> 941583 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 941583 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 941583 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:51325/a/r/collection1/
[junit4] 2> 941583 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 941583 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51325/a/r/collection1/ has
no replicas
[junit4] 2> 941583 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 941590 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51325/a/r/collection1/ shard3
[junit4] 2> 941699 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 941699 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 941754 INFO
(coreZkRegister-2571-thread-1-processing-n:127.0.0.1:51325_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51325_a%2Fr c:collection1 s:shard3 r:core_node2
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 941871 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 941871 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 942262 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001/cores/collection1
[junit4] 2> 942272 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001
[junit4] 2> 942273 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 942275 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2178cb7a{/a/r,null,AVAILABLE}
[junit4] 2> 942275 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@12e65d69{HTTP/1.1,[http/1.1]}{127.0.0.1:51329}
[junit4] 2> 942276 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server Started @949097ms
[junit4] 2> 942276 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/a/r, hostPort=51329,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001/cores}
[junit4] 2> 942276 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 942276 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 942276 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 942277 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 942277 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-23T04:13:06.554Z
[junit4] 2> 942281 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 942281 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001/solr.xml
[junit4] 2> 942291 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 942292 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51312/solr
[junit4] 2> 942309 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 942314 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 942318 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51329_a%2Fr
[junit4] 2> 942321 INFO (zkCallback-915-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 942321 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 942321 INFO
(zkCallback-911-thread-2-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 942321 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 942322 INFO
(zkCallback-933-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 942439 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001/cores
[junit4] 2> 942439 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 942441 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 942443 INFO
(OverseerStateUpdate-97840056016109572-127.0.0.1:51315_a%2Fr-n_0000000000)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 942553 INFO
(zkCallback-933-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr ] 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> 942553 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 942553 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 943468 WARN
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 943469 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.0
[junit4] 2> 943481 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 943570 WARN
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr 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> 943572 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 943608 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 943608 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 943609 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 943609 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b023dc
[junit4] 2> 943620 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.310546875,
floorSegmentMB=1.8974609375, forceMergeDeletesPctAllowed=1.49240552874922,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 943671 WARN
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 943791 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 943791 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 943804 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 943804 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 943817 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=44, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=93.7666015625,
floorSegmentMB=2.0625, forceMergeDeletesPctAllowed=6.277220002587455,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8751709416621902
[junit4] 2> 943827 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@58ac86d6[collection1] main]
[junit4] 2> 943829 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 943830 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 943830 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 943831 INFO
(searcherExecutor-2588-thread-1-processing-n:127.0.0.1:51329_a%2Fr
x:collection1 c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@58ac86d6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 943892 INFO
(coreLoadExecutor-2587-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1565440908375097344
[junit4] 2> 943905 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 943905 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 943906 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:51329/a/r/collection1/
[junit4] 2> 943906 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 943906 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51329/a/r/collection1/ has
no replicas
[junit4] 2> 943906 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 943911 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51329/a/r/collection1/ shard1
[junit4] 2> 944021 INFO
(zkCallback-933-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr ] 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> 944021 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 944021 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 944079 INFO
(coreZkRegister-2582-thread-1-processing-n:127.0.0.1:51329_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51329_a%2Fr c:collection1 s:shard1 r:core_node3
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 944200 INFO
(zkCallback-933-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr ] 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> 944200 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 944200 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 944681 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001/cores/collection1
[junit4] 2> 944683 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001
[junit4] 2> 944686 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 944688 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@730be1c{/a/r,null,AVAILABLE}
[junit4] 2> 944688 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@f2e96f9{HTTP/1.1,[http/1.1]}{127.0.0.1:51333}
[junit4] 2> 944688 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.Server Started @951510ms
[junit4] 2> 944689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/tempDir-001/jetty4,
solrconfig=solrconfig.xml, hostContext=/a/r, hostPort=51333,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001/cores}
[junit4] 2> 944689 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 944689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 944689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 944689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 944689 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-23T04:13:08.966Z
[junit4] 2> 944695 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 944695 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001/solr.xml
[junit4] 2> 944737 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 944739 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51312/solr
[junit4] 2> 944757 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51333_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 944763 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51333_a%2Fr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 944768 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51333_a%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51333_a%2Fr
[junit4] 2> 944773 INFO
(zkCallback-911-thread-1-processing-n:127.0.0.1:51315_a%2Fr)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 944773 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 944774 INFO (zkCallback-915-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 944774 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 944774 INFO
(zkCallback-933-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 944775 INFO
(zkCallback-939-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 944934 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51333_a%2Fr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001/cores
[junit4] 2> 944934 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E])
[n:127.0.0.1:51333_a%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 944935 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 944938 INFO
(OverseerStateUpdate-97840056016109572-127.0.0.1:51315_a%2Fr-n_0000000000)
[n:127.0.0.1:51315_a%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 945043 INFO
(zkCallback-921-thread-1-processing-n:127.0.0.1:51321_a%2Fr)
[n:127.0.0.1:51321_a%2Fr ] 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> 945043 INFO
(zkCallback-927-thread-1-processing-n:127.0.0.1:51325_a%2Fr)
[n:127.0.0.1:51325_a%2Fr ] 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> 945043 INFO
(zkCallback-939-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr ] 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> 945043 INFO
(zkCallback-933-thread-1-processing-n:127.0.0.1:51329_a%2Fr)
[n:127.0.0.1:51329_a%2Fr ] 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> 945958 WARN
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 945959 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.0
[junit4] 2> 945972 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 946067 WARN
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr 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> 946068 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 946102 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 946102 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 946103 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 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/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 946103 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b023dc
[junit4] 2> 946114 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=51.310546875,
floorSegmentMB=1.8974609375, forceMergeDeletesPctAllowed=1.49240552874922,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 946120 WARN
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 946243 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 946243 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 946246 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 946246 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 946251 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=44, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=93.7666015625,
floorSegmentMB=2.0625, forceMergeDeletesPctAllowed=6.277220002587455,
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8751709416621902
[junit4] 2> 946252 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@e9f67c6[collection1] main]
[junit4] 2> 946254 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 946254 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 946254 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 946255 INFO
(searcherExecutor-2599-thread-1-processing-n:127.0.0.1:51333_a%2Fr
x:collection1 c:collection1) [n:127.0.0.1:51333_a%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@e9f67c6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 946263 INFO
(coreLoadExecutor-2598-thread-1-processing-n:127.0.0.1:51333_a%2Fr)
[n:127.0.0.1:51333_a%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1565440910861271040
[junit4] 2> 946269 INFO
(coreZkRegister-2593-thread-1-processing-n:127.0.0.1:51333_a%2Fr x:collection1
c:collection1) [n:127.0.0.1:51333_a%2Fr c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.c.ZkController Core needs t
[...truncated too long message...]
e1c{/a/r,null,UNAVAILABLE}
[junit4] 2> 1007329 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ChaosMonkey monkey: stop shard! 51345
[junit4] 2> 1007329 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=188544408
[junit4] 2> 1007333 WARN
(zkCallback-946-thread-1-processing-n:127.0.0.1:51345_a%2Fr)
[n:127.0.0.1:51345_a%2Fr ] o.a.s.c.c.ZkStateReader ZooKeeper watch
triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/live_nodes]
[junit4] 2> 1007334 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1007335 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@6606d97d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1007335 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@431c6c93{/a/r,null,UNAVAILABLE}
[junit4] 2> 1007336 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ChaosMonkey monkey: stop shard! 51357
[junit4] 2> 1007337 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[7ADD612BFEF1374E]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:51312 51312
[junit4] 2> 1007486 INFO (Thread-1150) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:51312 51312
[junit4] 2> 1016490 WARN (Thread-1150) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/aliases.json
[junit4] 2> 14 /solr/clusterprops.json
[junit4] 2> 11 /solr/configs/conf1
[junit4] 2> 7 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/clusterstate.json
[junit4] 2> 6 /solr/collections/collection1/state.json
[junit4] 2> 5 /solr/collections/collection2/state.json
[junit4] 2> 2
/solr/collections/collection2/leader_elect/shard2/election/97840056016109572-core_node19-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/97840056016109580-127.0.0.1:51325_a%2Fr-n_0000000002
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 14 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=FullSolrCloudDistribCmdsTest -Dtests.method=test
-Dtests.seed=7ADD612BFEF1374E -Dtests.slow=true -Dtests.locale=vi
-Dtests.timezone=EST -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 84.2s J1 | FullSolrCloudDistribCmdsTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find
collection:collection2
[junit4] > at
__randomizedtesting.SeedInfo.seed([7ADD612BFEF1374E:F2895EF1500D5AB6]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:159)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:144)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:139)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
[junit4] > at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:620)
[junit4] > at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1016530 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[7ADD612BFEF1374E]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_7ADD612BFEF1374E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{6grand2_s=Lucene50(blocksize=128),
7grand5_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
5grand1_s=Lucene50(blocksize=128),
6grand4_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
11parent_f1_s=Lucene50(blocksize=128), 4grand0_s=Lucene50(blocksize=128),
7grand1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
4grand2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
7grand3_s=Lucene50(blocksize=128), 6parent_f2_s=FST50,
0parent_f2_s=PostingsFormat(name=Direct),
3grand1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
4parent_f1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
9parent_f2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=FST50,
5grand3_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), 5parent_f1_s=FST50,
1parent_f1_s=FST50, 6grand0_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
10parent_f2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
_root_=Lucene50(blocksize=128),
8parent_f1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
2parent_f2_s=FST50, 3child0_s=FST50, 4child3_s=FST50,
1grand0_s=PostingsFormat(name=Direct), 4child1_s=PostingsFormat(name=Direct),
2grand1_s=FST50, 3child2_s=PostingsFormat(name=Direct),
1child0_s=PostingsFormat(name=Direct),
7parent_f1_s=PostingsFormat(name=Direct), 2child1_s=FST50,
3parent_f2_s=Lucene50(blocksize=128), 6grand3_s=PostingsFormat(name=Direct),
7grand6_s=PostingsFormat(name=Direct), 5grand0_s=PostingsFormat(name=Direct),
6grand5_s=FST50, 5grand2_s=FST50, 7grand2_s=PostingsFormat(name=Direct),
11parent_f2_s=PostingsFormat(name=Direct),
4grand1_s=PostingsFormat(name=Direct), 7grand4_s=FST50, 4grand3_s=FST50,
3grand0_s=FST50, 6parent_f1_s=Lucene50(blocksize=128),
0parent_f1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
3grand2_s=PostingsFormat(name=Direct),
4parent_f2_s=PostingsFormat(name=Direct), 9parent_f1_s=FST50,
1parent_f2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), 7grand0_s=FST50,
text_t=Lucene50(blocksize=128), 5grand4_s=PostingsFormat(name=Direct),
6grand1_s=FST50, type_s=Lucene50(blocksize=128),
5parent_f2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
8parent_f2_s=PostingsFormat(name=Direct),
10parent_f1_s=PostingsFormat(name=Direct),
2parent_f1_s=Lucene50(blocksize=128), 4child2_s=Lucene50(blocksize=128),
rnd_b=FST50, 4child0_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
2grand0_s=Lucene50(blocksize=128),
3child1_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
7parent_f2_s=Lucene50(blocksize=128),
2child0_s=PostingsFormat(name=LuceneVarGapDocFreqInterval),
3parent_f1_s=PostingsFormat(name=Direct)},
docValues:{_version_=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1854,
maxMBSortInHeap=5.156517714635799,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=vi, timezone=EST
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_121
(64-bit)/cpus=3,threads=1,free=327499400,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestNoOpRegenerator,
QueryParsingTest, TestAtomicUpdateErrorCases, DistributedIntervalFacetingTest,
WordBreakSolrSpellCheckerTest, TestDFRSimilarityFactory,
SpellPossibilityIteratorTest, FieldMutatingUpdateProcessorTest,
TermVectorComponentTest, TestBackupRepositoryFactory, OverseerRolesTest,
TestCloudManagedSchema, CollectionTooManyReplicasTest,
TestManagedStopFilterFactory, BinaryUpdateRequestHandlerTest,
ResourceLoaderTest, TestFieldCollectionResource, TestRandomRequestDistribution,
ForceLeaderTest, TestDocSet, TestLeaderInitiatedRecoveryThread, TestBadConfig,
SuggestComponentTest, TestJoin, TestRandomFaceting, SolrIndexMetricsTest,
JvmMetricsTest, TestDistribDocBasedVersion,
AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrCoreParser,
RequestHandlersTest, SecurityConfHandlerTest, TestCustomStream,
CircularListTest, ZkSolrClientTest, SolrSlf4jReporterTest,
HdfsUnloadDistributedZkTest, TestTestInjection, CollectionReloadTest,
DistribCursorPagingTest, TestPathTrie, TriLevelCompositeIdRoutingTest,
ClassificationUpdateProcessorFactoryTest, ChangedSchemaMergeTest, RulesTest,
TestSmileRequest, ChaosMonkeySafeLeaderTest, UUIDFieldTest,
SolrCoreCheckLockOnStartupTest, TestPHPSerializedResponseWriter,
TestSystemIdResolver, TestLMJelinekMercerSimilarityFactory,
TestOnReconnectListenerSupport, FileBasedSpellCheckerTest,
HighlighterConfigTest, CheckHdfsIndexTest, DeleteInactiveReplicaTest,
TestSolrCoreProperties, TestCollectionAPI, TestExceedMaxTermLength,
MinimalSchemaTest, TestFieldCacheWithThreads, DocValuesMissingTest,
SolrInfoMBeanTest, TestJavabinTupleStreamParser, ConfigureRecoveryStrategyTest,
TestCopyFieldCollectionResource, SortByFunctionTest, TestFiltering,
TestReqParamsAPI, TestTrie, MetricUtilsTest, ConvertedLegacyTest,
TestCoreDiscovery, SpellCheckCollatorWithCollapseTest, LukeRequestHandlerTest,
TestFieldTypeCollectionResource, AsyncCallRequestStatusResponseTest,
LeaderInitiatedRecoveryOnShardRestartTest, RestartWhileUpdatingTest,
DistributedFacetPivotLargeTest, JsonLoaderTest, SolrGraphiteReporterTest,
TestFoldingMultitermQuery, GraphQueryTest, ClusterStateTest,
TestSlowCompositeReaderWrapper, FullSolrCloudDistribCmdsTest]
[junit4] Completed [160/704 (1!)] on J1 in 84.22s, 1 test, 1 failure <<<
FAILURES!
[...truncated 45195 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
