Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4051/
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([EAD10CBA31337BF7:628533609FCF160F]: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:910)
at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:612)
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:985)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12035 lines...]
[junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/init-core-data-001
[junit4] 2> 1480721 WARN
(SUITE-FullSolrCloudDistribCmdsTest-seed#[EAD10CBA31337BF7]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 1480721 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[EAD10CBA31337BF7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 1480725 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[EAD10CBA31337BF7]-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> 1480725 INFO
(SUITE-FullSolrCloudDistribCmdsTest-seed#[EAD10CBA31337BF7]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/js_of/ix
[junit4] 2> 1480727 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1480727 INFO (Thread-3031) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1480727 INFO (Thread-3031) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1480730 ERROR (Thread-3031) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 1480841 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkTestServer start zk server on port:64742
[junit4] 2> 1480887 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1480890 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 1480893 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1480895 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1480896 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1480901 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1480903 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1480905 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1480908 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1480910 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1480912 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractZkTestCase put
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1480915 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 1481261 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/control-001/cores/collection1
[junit4] 2> 1481263 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1481264 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4dc2351a{/js_of/ix,null,AVAILABLE}
[junit4] 2> 1481267 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@10c49434{HTTP/1.1,[http/1.1]}{127.0.0.1:64747}
[junit4] 2> 1481267 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server Started @1484801ms
[junit4] 2> 1481268 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/tempDir-001/control/data,
hostContext=/js_of/ix, hostPort=64747,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/control-001/cores}
[junit4] 2> 1481268 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1481269 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1481269 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1481269 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1481269 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-06T20:32:29.712Z
[junit4] 2> 1481277 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1481277 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/control-001/solr.xml
[junit4] 2> 1481284 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1481288 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64742/solr
[junit4] 2> 1481328 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1481329 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.OverseerElectionContext I am going
to be the leader 127.0.0.1:64747_js_of%2Fix
[junit4] 2> 1481331 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.Overseer Overseer
(id=98093049392136196-127.0.0.1:64747_js_of%2Fix-n_0000000000) starting
[junit4] 2> 1481342 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:64747_js_of%2Fix
[junit4] 2> 1481345 INFO
(zkCallback-3681-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 1481433 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1481439 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1481439 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1481441 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/control-001/cores
[junit4] 2> 1481441 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1481442 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1481446 INFO
(OverseerStateUpdate-98093049392136196-127.0.0.1:64747_js_of%2Fix-n_0000000000)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard1
[junit4] 2> 1482475 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1482488 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1482577 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1482616 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection, trusted=true
[junit4] 2> 1482617 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.core_node1' (registry
'solr.core.control_collection.shard1.core_node1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1482617 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1482617 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/control-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/control-001/cores/collection1/data/]
[junit4] 2> 1482619 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=68.705078125,
floorSegmentMB=1.5263671875, forceMergeDeletesPctAllowed=13.886242955899698,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1482621 WARN
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix 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> 1482743 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1482743 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1482744 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1482744 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1482745 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=54.33203125,
floorSegmentMB=2.13671875, forceMergeDeletesPctAllowed=13.16852413269603,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.34695920841250194
[junit4] 2> 1482745 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@57d87065[collection1] main]
[junit4] 2> 1482751 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1482751 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1482752 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1482753 INFO
(searcherExecutor-7692-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
c:control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registered
new searcher Searcher@57d87065[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1482753 INFO
(coreLoadExecutor-7691-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix c:control_collection x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1569488792396496896
[junit4] 2> 1482760 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1482761 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
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> 1482761 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:64747/js_of/ix/collection1/
[junit4] 2> 1482761 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1482761 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:64747/js_of/ix/collection1/ has no replicas
[junit4] 2> 1482761 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
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> 1482767 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
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:64747/js_of/ix/collection1/ shard1
[junit4] 2> 1482890 INFO
(coreZkRegister-7684-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix
x:collection1 c:control_collection) [n:127.0.0.1:64747_js_of%2Fix
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1483001 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1483002 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64742/solr ready
[junit4] 2> 1483002 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1483312 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001/cores/collection1
[junit4] 2> 1483313 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001
of type NRT
[junit4] 2> 1483314 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1483315 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6d07d449{/js_of/ix,null,AVAILABLE}
[junit4] 2> 1483317 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@7ced7b3e{HTTP/1.1,[http/1.1]}{127.0.0.1:64759}
[junit4] 2> 1483317 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server Started @1486850ms
[junit4] 2> 1483317 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/js_of/ix, hostPort=64759,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001/cores}
[junit4] 2> 1483317 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1483320 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1483320 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1483321 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1483321 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-06T20:32:31.764Z
[junit4] 2> 1483328 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1483328 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001/solr.xml
[junit4] 2> 1483334 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1483342 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64742/solr
[junit4] 2> 1483354 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 1483358 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1483361 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:64759_js_of%2Fix
[junit4] 2> 1483364 INFO
(zkCallback-3681-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (1) -> (2)
[junit4] 2> 1483364 INFO
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (1) -> (2)
[junit4] 2> 1483364 INFO (zkCallback-3685-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1483481 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1483491 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1483491 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1483494 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001/cores
[junit4] 2> 1483494 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1483495 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1483497 INFO
(OverseerStateUpdate-98093049392136196-127.0.0.1:64747_js_of%2Fix-n_0000000000)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard2
[junit4] 2> 1484527 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1484549 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1484628 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1484652 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1484652 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.core_node1' (registry
'solr.core.collection1.shard2.core_node1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1484652 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1484653 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 1484654 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=68.705078125,
floorSegmentMB=1.5263671875, forceMergeDeletesPctAllowed=13.886242955899698,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1484657 WARN
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix 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> 1484727 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1484727 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1484729 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1484730 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1484730 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=54.33203125,
floorSegmentMB=2.13671875, forceMergeDeletesPctAllowed=13.16852413269603,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.34695920841250194
[junit4] 2> 1484731 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@41a27873[collection1] main]
[junit4] 2> 1484732 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1484733 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1484733 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1484733 INFO
(coreLoadExecutor-7702-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569488794472677376
[junit4] 2> 1484734 INFO
(searcherExecutor-7703-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@41a27873[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1484750 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 1484750 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix 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> 1484750 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:64759/js_of/ix/collection1/
[junit4] 2> 1484750 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 1484750 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:64759/js_of/ix/collection1/ has no replicas
[junit4] 2> 1484750 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found
all replicas participating in election, clear LIR
[junit4] 2> 1484756 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:64759/js_of/ix/collection1/ shard2
[junit4] 2> 1484875 INFO
(coreZkRegister-7697-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64759_js_of%2Fix c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 1485344 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001/cores/collection1
[junit4] 2> 1485346 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001
of type NRT
[junit4] 2> 1485346 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1485348 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@523f87a5{/js_of/ix,null,AVAILABLE}
[junit4] 2> 1485348 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@69e437be{HTTP/1.1,[http/1.1]}{127.0.0.1:64767}
[junit4] 2> 1485349 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server Started @1488882ms
[junit4] 2> 1485349 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/tempDir-001/jetty2,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/js_of/ix,
hostPort=64767,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001/cores}
[junit4] 2> 1485349 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1485350 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1485350 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1485350 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1485350 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-06T20:32:33.793Z
[junit4] 2> 1485353 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1485353 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001/solr.xml
[junit4] 2> 1485360 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1485365 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64742/solr
[junit4] 2> 1485378 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (2)
[junit4] 2> 1485381 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1485385 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:64767_js_of%2Fix
[junit4] 2> 1485387 INFO
(zkCallback-3681-thread-1-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (2) -> (3)
[junit4] 2> 1485387 INFO
(zkCallback-3696-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (2) -> (3)
[junit4] 2> 1485387 INFO
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (2) -> (3)
[junit4] 2> 1485387 INFO (zkCallback-3685-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1485553 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1485559 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1485559 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1485563 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001/cores
[junit4] 2> 1485563 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1485563 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1485570 INFO
(OverseerStateUpdate-98093049392136196-127.0.0.1:64747_js_of%2Fix-n_0000000000)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard3
[junit4] 2> 1486594 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1486602 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1486658 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1486685 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1486685 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard3.core_node2' (registry
'solr.core.collection1.shard3.core_node2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1486686 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1486686 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 1486688 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=68.705078125,
floorSegmentMB=1.5263671875, forceMergeDeletesPctAllowed=13.886242955899698,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1486690 WARN
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix 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> 1486752 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1486752 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1486753 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1486753 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1486753 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=54.33203125,
floorSegmentMB=2.13671875, forceMergeDeletesPctAllowed=13.16852413269603,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.34695920841250194
[junit4] 2> 1486754 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@595ea4ca[collection1] main]
[junit4] 2> 1486755 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1486756 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1486756 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1486756 INFO
(coreLoadExecutor-7713-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569488796593946624
[junit4] 2> 1486757 INFO
(searcherExecutor-7714-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@595ea4ca[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1486764 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 1486764 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix 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> 1486764 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:64767/js_of/ix/collection1/
[junit4] 2> 1486764 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 1486764 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:64767/js_of/ix/collection1/ has no replicas
[junit4] 2> 1486764 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Found
all replicas participating in election, clear LIR
[junit4] 2> 1486770 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:64767/js_of/ix/collection1/ shard3
[junit4] 2> 1486940 INFO
(coreZkRegister-7708-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64767_js_of%2Fix c:collection1
s:shard3 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 1487428 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001/cores/collection1
[junit4] 2> 1487429 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001
of type NRT
[junit4] 2> 1487429 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1487431 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2344f7ea{/js_of/ix,null,AVAILABLE}
[junit4] 2> 1487431 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@ab30c0c{HTTP/1.1,[http/1.1]}{127.0.0.1:64778}
[junit4] 2> 1487431 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server Started @1490965ms
[junit4] 2> 1487432 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/js_of/ix, hostPort=64778,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001/cores}
[junit4] 2> 1487432 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1487432 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1487432 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1487432 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1487432 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-06T20:32:35.875Z
[junit4] 2> 1487436 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1487436 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001/solr.xml
[junit4] 2> 1487442 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1487446 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64742/solr
[junit4] 2> 1487460 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (3)
[junit4] 2> 1487468 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1487471 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:64778_js_of%2Fix
[junit4] 2> 1487473 INFO
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 1487474 INFO
(zkCallback-3681-thread-2-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 1487474 INFO
(zkCallback-3702-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 1487474 INFO
(zkCallback-3696-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 1487474 INFO (zkCallback-3685-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1487596 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1487602 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1487602 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1487605 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001/cores
[junit4] 2> 1487605 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1487605 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1487607 INFO
(OverseerStateUpdate-98093049392136196-127.0.0.1:64747_js_of%2Fix-n_0000000000)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard1
[junit4] 2> 1488630 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1488639 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1488709 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1488736 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1488737 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.core_node3' (registry
'solr.core.collection1.shard1.core_node3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1488737 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1488737 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 1488738 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=68.705078125,
floorSegmentMB=1.5263671875, forceMergeDeletesPctAllowed=13.886242955899698,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1488741 WARN
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix 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> 1488808 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1488808 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1488809 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1488809 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1488810 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=54.33203125,
floorSegmentMB=2.13671875, forceMergeDeletesPctAllowed=13.16852413269603,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.34695920841250194
[junit4] 2> 1488810 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6625b1e5[collection1] main]
[junit4] 2> 1488815 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1488815 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1488816 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1488816 INFO
(coreLoadExecutor-7724-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569488798754013184
[junit4] 2> 1488819 INFO
(searcherExecutor-7725-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6625b1e5[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1488827 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 1488827 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix 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> 1488827 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:64778/js_of/ix/collection1/
[junit4] 2> 1488827 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 1488827 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:64778/js_of/ix/collection1/ has no replicas
[junit4] 2> 1488828 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext Found
all replicas participating in election, clear LIR
[junit4] 2> 1488833 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:64778/js_of/ix/collection1/ shard1
[junit4] 2> 1489005 INFO
(coreZkRegister-7719-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64778_js_of%2Fix c:collection1
s:shard1 r:core_node3 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 1489454 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001/cores/collection1
[junit4] 2> 1489455 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001
of type NRT
[junit4] 2> 1489456 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1489457 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3da7ea46{/js_of/ix,null,AVAILABLE}
[junit4] 2> 1489458 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6e05328{HTTP/1.1,[http/1.1]}{127.0.0.1:64789}
[junit4] 2> 1489458 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.Server Started @1492991ms
[junit4] 2> 1489458 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/tempDir-001/jetty4,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/js_of/ix,
hostPort=64789,
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001/cores}
[junit4] 2> 1489459 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1489459 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 1489459 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1489459 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1489459 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-06T20:32:37.902Z
[junit4] 2> 1489462 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1489462 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001/solr.xml
[junit4] 2> 1489470 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 1489475 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64742/solr
[junit4] 2> 1489489 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (4)
[junit4] 2> 1489493 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1489497 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:64789_js_of%2Fix
[junit4] 2> 1489499 INFO
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64759_js_of%2Fix)
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (4) -> (5)
[junit4] 2> 1489499 INFO
(zkCallback-3702-thread-1-processing-n:127.0.0.1:64778_js_of%2Fix)
[n:127.0.0.1:64778_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (4) -> (5)
[junit4] 2> 1489500 INFO
(zkCallback-3708-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (4) -> (5)
[junit4] 2> 1489500 INFO (zkCallback-3685-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1489500 INFO
(zkCallback-3681-thread-2-processing-n:127.0.0.1:64747_js_of%2Fix)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (4) -> (5)
[junit4] 2> 1489500 INFO
(zkCallback-3696-thread-1-processing-n:127.0.0.1:64767_js_of%2Fix)
[n:127.0.0.1:64767_js_of%2Fix ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (4) -> (5)
[junit4] 2> 1489606 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1489612 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1489612 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1489615 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001/cores
[junit4] 2> 1489615 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7])
[n:127.0.0.1:64789_js_of%2Fix ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 1489615 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 1489617 INFO
(OverseerStateUpdate-98093049392136196-127.0.0.1:64747_js_of%2Fix-n_0000000000)
[n:127.0.0.1:64747_js_of%2Fix ] o.a.s.c.o.ReplicaMutator Assigning new node
to shard shard=shard2
[junit4] 2> 1490643 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1490651 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1490741 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1490767 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1, trusted=true
[junit4] 2> 1490767 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard2.core_node4' (registry
'solr.core.collection1.shard2.core_node4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4239d25d
[junit4] 2> 1490767 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1490767 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001/cores/collection1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 1490769 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=68.705078125,
floorSegmentMB=1.5263671875, forceMergeDeletesPctAllowed=13.886242955899698,
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1490772 WARN
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix 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> 1490835 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1490835 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1490836 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1490836 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1490837 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=21, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=54.33203125,
floorSegmentMB=2.13671875, forceMergeDeletesPctAllowed=13.16852413269603,
segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.34695920841250194
[junit4] 2> 1490837 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3da00680[collection1] main]
[junit4] 2> 1490839 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1490840 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1490840 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1490841 INFO
(coreLoadExecutor-7735-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569488800877379584
[junit4] 2> 1490842 INFO
(searcherExecutor-7736-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64789_js_of%2Fix c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3da00680[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1490845 INFO
(coreZkRegister-7730-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 c:collection1) [n:127.0.0.1:64789_js_of%2Fix c:collection1
s:shard2 r:core_node4 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 1490846 INFO
(updateExecutor-3705-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1490846 INFO
(recoveryExecutor-3706-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 1490847 INFO
(recoveryExecutor-3706-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1490847 INFO
(recoveryExecutor-3706-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 1490847 INFO
(recoveryExecutor-3706-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1490847 INFO
(recoveryExecutor-3706-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core [collection1]
as recovering, leader is [http://127.0.0.1:64759/js_of/ix/collection1/] and I
am [http://127.0.0.1:64789/js_of/ix/collection1/]
[junit4] 2> 1490851 INFO
(recoveryExecutor-3706-thread-1-processing-n:127.0.0.1:64789_js_of%2Fix
x:collection1 s:shard2 c:collection1 r:core_node4)
[n:127.0.0.1:64789_js_of%2Fix c:collection1 s:shard2 r:core_node4
x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:64759/js_of/ix]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:64789_js_of%252Fix&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1490854 INFO (qtp75435237-17028)
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.h.a.PrepRecoveryOp Going to wait for
coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLeader:
true, onlyIfLeaderActive: true, maxTime: 183 s
[junit4] 2> 1490855 INFO (qtp75435237-17028)
[n:127.0.0.1:64759_js_of%2Fix ] o.a.s.h.a.PrepRecoveryOp In
WaitForState(recovering): collection=collection1, shard=shard2,
thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true
[...truncated too long message...]
31337BF7]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer
instance=1289815578
[junit4] 2> 1544202 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1544202 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1544203 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1544204 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1544207 WARN
(zkCallback-3715-thread-1-processing-n:127.0.0.1:64798_js_of%2Fix)
[n:127.0.0.1:64798_js_of%2Fix ] o.a.s.c.c.ZkStateReader ZooKeeper watch
triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/live_nodes]
[junit4] 2> 1544213 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@4fe2163b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1544213 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@637f638f{/js_of/ix,null,UNAVAILABLE}
[junit4] 2> 1544214 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ChaosMonkey monkey: stop jetty! 64811
[junit4] 2> 1544215 ERROR
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1544216 INFO
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[EAD10CBA31337BF7]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:64742 64742
[junit4] 2> 1544225 INFO (Thread-3031) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:64742 64742
[junit4] 2> 1544792 WARN (Thread-3031) [ ] 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> 10 /solr/configs/conf1
[junit4] 2> 7 /solr/security.json
[junit4] 2> 6 /solr/collections/collection1/state.json
[junit4] 2> 5 /solr/collections/collection2/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/clusterstate.json
[junit4] 2> 2
/solr/overseer_elect/election/98093049392136203-127.0.0.1:64767_js_of%2Fix-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> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=FullSolrCloudDistribCmdsTest -Dtests.method=test
-Dtests.seed=EAD10CBA31337BF7 -Dtests.slow=true -Dtests.locale=sr-BA
-Dtests.timezone=Europe/Dublin -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 64.1s J1 | FullSolrCloudDistribCmdsTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find
collection:collection2
[junit4] > at
__randomizedtesting.SeedInfo.seed([EAD10CBA31337BF7:628533609FCF160F]: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:910)
[junit4] > at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:612)
[junit4] > at
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EAD10CBA31337BF7-001
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=RandomSimilarity(queryNorm=false): {}, locale=sr-BA, timezone=Europe/Dublin
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131
(64-bit)/cpus=3,threads=1,free=291627800,total=532676608
[junit4] 2> NOTE: All tests run in this JVM: [TestRebalanceLeaders,
TestDistributedGrouping, SolrCoreMetricManagerTest, TestCloudSchemaless,
TestSolr4Spatial, TestReversedWildcardFilterFactory,
TestShortCircuitedRequests, SmileWriterTest, TestUninvertingReader,
TestSolrCoreParser, ShardRoutingCustomTest, TestManagedSynonymFilterFactory,
JsonLoaderTest, TriLevelCompositeIdRoutingTest, SolrCLIZkUtilsTest,
SuggesterTSTTest, TestIndexingPerformance, TestDownShardTolerantSearch,
TestCopyFieldCollectionResource, BasicFunctionalityTest,
TemplateUpdateProcessorTest, TestReload, FieldMutatingUpdateProcessorTest,
TestMissingGroups, StressHdfsTest, TestBadConfig, BasicAuthIntegrationTest,
AutoScalingHandlerTest, TestQueryWrapperFilter, UpdateLogTest,
TestUniqueKeyFieldResource, TestImpersonationWithHadoopAuth,
TestFoldingMultitermQuery, TestEmbeddedSolrServerSchemaAPI, CircularListTest,
DistributedVersionInfoTest, RequestHandlersTest, CoreAdminHandlerTest,
CollectionTooManyReplicasTest, HttpSolrCallGetCoreTest, ScriptEngineTest,
BlockJoinFacetRandomTest, TestCodecSupport, ManagedSchemaRoundRobinCloudTest,
ZkSolrClientTest, TestAnalyzedSuggestions, SolrJmxReporterTest,
FileBasedSpellCheckerTest, TestHalfAndHalfDocValues, TestDocTermOrds,
TestComplexPhraseLeadingWildcard, TestExceedMaxTermLength,
URLClassifyProcessorTest, TestRandomFlRTGCloud, TestStandardQParsers,
SpatialRPTFieldTypeTest, DirectoryFactoryTest, DeleteNodeTest,
TestSolrIndexConfig, IndexSchemaTest, TestCrossCoreJoin, TestHashQParserPlugin,
AddSchemaFieldsUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions,
LeaderElectionIntegrationTest, DistributedExpandComponentTest,
UpdateParamsTest, DistribCursorPagingTest, TestSQLHandler, TestSmileRequest,
SuggesterWFSTTest, TestUseDocValuesAsStored2, TestFieldCacheReopen,
TestFieldCacheSortRandom, TestFastWriter, TestFieldTypeResource,
CdcrReplicationHandlerTest, TestSystemCollAutoCreate, TermVectorComponentTest,
TestReloadAndDeleteDocs, TestConfigSets, TestScoreJoinQPScore,
TestLocalFSCloudBackupRestore, TestDeleteCollectionOnDownNodes,
SharedFSAutoReplicaFailoverUtilsTest, DistributedFacetPivotSmallAdvancedTest,
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestValueSourceCache,
SecurityConfHandlerTest, SSLMigrationTest, TestCustomStream,
TestCloudPseudoReturnFields, SubstringBytesRefFilterTest, CleanupOldIndexTest,
TestSimpleTextCodec, TestTestInjection, TestDocSet, TestTlogReplica,
QueryParsingTest, TestOmitPositions, NoCacheHeaderTest, TestConfig,
TestDFRSimilarityFactory, NotRequiredUniqueKeyTest, TestFilteredDocIdSet,
FieldAnalysisRequestHandlerTest, BasicDistributedZk2Test, SolrIndexMetricsTest,
MissingSegmentRecoveryTest, TestFastLRUCache, TestManagedResourceStorage,
TestFileDictionaryLookup, BigEndianAscendingWordSerializerTest,
TestPivotHelperCode, TestTrieFacet, TestXmlQParserPlugin,
TestMaxTokenLenTokenizer, TestConfigSetImmutable, TestClusterProperties,
TestLegacyTerms, CloneFieldUpdateProcessorFactoryTest,
TestSolrCloudWithDelegationTokens, DistributedIntervalFacetingTest,
EchoParamsTest, CdcrVersionReplicationTest, AsyncCallRequestStatusResponseTest,
ReplicaListTransformerTest, TestRecovery, TestSolrQueryResponse,
OverseerTaskQueueTest, ConfigSetsAPITest, TestWriterPerf, AliasIntegrationTest,
TestBulkSchemaAPI, TestRawResponseWriter, HdfsUnloadDistributedZkTest,
TestFiltering, TestMiniSolrCloudCluster, PrimUtilsTest, SpellCheckCollatorTest,
TestNumericRangeQuery32, ChaosMonkeySafeLeaderWithPullReplicasTest,
TestIntervalFaceting, TestCoreDiscovery, TestSubQueryTransformerDistrib,
TestPHPSerializedResponseWriter, SolrMetricReporterTest, TestRandomFaceting,
CloudMLTQParserTest, AnalysisErrorHandlingTest, TestPullReplica,
AtomicUpdatesTest, TestSolrXml, CreateCollectionCleanupTest, OverseerRolesTest,
TestDistributedMissingSort, SortByFunctionTest, TestConfigSetsAPI,
TestLMJelinekMercerSimilarityFactory, CollectionReloadTest, ReplaceNodeTest,
TestSchemalessBufferedUpdates, SolrCloudReportersTest, LoggingHandlerTest,
SolrCoreCheckLockOnStartupTest, DirectUpdateHandlerOptimizeTest,
CheckHdfsIndexTest, DeleteInactiveReplicaTest, TestNestedDocsSort,
TestFieldResource, TestSchemaResource, TestSolrDeletionPolicy2,
TestSubQueryTransformer, TestManagedSchemaAPI, TestNoOpRegenerator,
CurrencyFieldOpenExchangeTest, TestClassicSimilarityFactory,
ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest,
CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest,
OverseerTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest]
[junit4] Completed [373/726 (1!)] on J1 in 64.10s, 1 test, 1 failure <<<
FAILURES!
[...truncated 44578 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
