Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1864/
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
Could not get expected value
'org.apache.solr.core.BlobStoreTestRequestHandler' for path
'overlay/requestHandler/\/test1/class' full output: { "responseHeader":{
"status":0, "QTime":0}, "overlay":{ "znodeVersion":0,
"runtimeLib":{"colltest":{ "name":"colltest", "version":1}}}},
from server: null
Stack Trace:
java.lang.AssertionError: Could not get expected value
'org.apache.solr.core.BlobStoreTestRequestHandler' for path
'overlay/requestHandler/\/test1/class' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"overlay":{
"znodeVersion":0,
"runtimeLib":{"colltest":{
"name":"colltest",
"version":1}}}}, from server: null
at
__randomizedtesting.SeedInfo.seed([C3406D32319C9C27:1B0D4065C6413987]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
at
org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:97)
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 12798 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/init-core-data-001
[junit4] 2> 2124446 WARN
(SUITE-TestDynamicLoading-seed#[C3406D32319C9C27]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 2124447 INFO
(SUITE-TestDynamicLoading-seed#[C3406D32319C9C27]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 2124448 INFO
(SUITE-TestDynamicLoading-seed#[C3406D32319C9C27]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2124448 INFO
(SUITE-TestDynamicLoading-seed#[C3406D32319C9C27]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2124452 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2124452 INFO (Thread-8065) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2124452 INFO (Thread-8065) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2124454 ERROR (Thread-8065) [ ] 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> 2124552 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkTestServer start zk server on port:49927
[junit4] 2> 2124563 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 2124566 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 2124568 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2124569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 2124570 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 2124572 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 2124573 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 2124575 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2124576 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2124578 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 2124579 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 2124583 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly
asked otherwise
[junit4] 2> 2124698 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/control-001/cores/collection1
[junit4] 2> 2124702 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2124703 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@464a3083{/,null,AVAILABLE}
[junit4] 2> 2124704 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@77a6a1d8{SSL,[ssl,
http/1.1]}{127.0.0.1:53223}
[junit4] 2> 2124704 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server Started @2127679ms
[junit4] 2> 2124704 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/tempDir-001/control/data,
hostContext=/, hostPort=53223,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/control-001/cores}
[junit4] 2> 2124705 ERROR
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2124705 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 2124705 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2124705 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2124705 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-15T03:20:04.574Z
[junit4] 2> 2124710 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2124710 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/control-001/solr.xml
[junit4] 2> 2124715 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2124719 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49927/solr
[junit4] 2> 2124762 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2124763 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:53223_
[junit4] 2> 2124764 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.c.Overseer Overseer
(id=98139950568046597-127.0.0.1:53223_-n_0000000000) starting
[junit4] 2> 2124772 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53223_
[junit4] 2> 2124774 INFO
(zkCallback-3255-thread-1-processing-n:127.0.0.1:53223_) [n:127.0.0.1:53223_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2124906 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2124912 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2124912 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2124914 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/control-001/cores
[junit4] 2> 2124914 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53223_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2124915 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 2124921 INFO
(OverseerStateUpdate-98139950568046597-127.0.0.1:53223_-n_0000000000)
[n:127.0.0.1:53223_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2124938 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2124964 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2125091 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2125106 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection, trusted=true
[junit4] 2> 2125106 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ 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@5f749b6d
[junit4] 2> 2125106 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2125106 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/control-001/cores/collection1/data/]
[junit4] 2> 2125108 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=46, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=8.55078125,
floorSegmentMB=0.9296875, forceMergeDeletesPctAllowed=16.954813802468983,
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2125111 WARN
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ 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> 2125157 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2125157 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2125158 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2125158 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2125159 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=64.1650390625,
floorSegmentMB=1.814453125, forceMergeDeletesPctAllowed=25.168749597198776,
segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2125160 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@45b9d820[collection1] main]
[junit4] 2> 2125161 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2125161 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2125162 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2125163 INFO
(searcherExecutor-8065-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@45b9d820[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2125163 INFO
(coreLoadExecutor-8064-thread-1-processing-n:127.0.0.1:53223_)
[n:127.0.0.1:53223_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570239209833234432
[junit4] 2> 2125170 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 2125170 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ 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> 2125171 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:53223/collection1/
[junit4] 2> 2125171 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 2125171 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
https://127.0.0.1:53223/collection1/ has no replicas
[junit4] 2> 2125171 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ 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> 2125175 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: https://127.0.0.1:53223/collection1/ shard1
[junit4] 2> 2125326 INFO
(coreZkRegister-8057-thread-1-processing-n:127.0.0.1:53223_ x:collection1
c:control_collection) [n:127.0.0.1:53223_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 2125443 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2125444 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49927/solr ready
[junit4] 2> 2125444 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 2125444 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 2125564 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001/cores/collection1
[junit4] 2> 2125564 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001
of type NRT
[junit4] 2> 2125566 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2125567 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@49408bad{/,null,AVAILABLE}
[junit4] 2> 2125568 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@d310250{SSL,[ssl,
http/1.1]}{127.0.0.1:48520}
[junit4] 2> 2125569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server Started @2128543ms
[junit4] 2> 2125569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/tempDir-001/jetty1,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=48520,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001/cores}
[junit4] 2> 2125569 ERROR
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2125569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 2125569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2125569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2125569 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-15T03:20:05.438Z
[junit4] 2> 2125572 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2125572 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001/solr.xml
[junit4] 2> 2125578 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2125581 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49927/solr
[junit4] 2> 2125590 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2125592 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2125594 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:48520_
[junit4] 2> 2125596 INFO
(zkCallback-3255-thread-2-processing-n:127.0.0.1:53223_) [n:127.0.0.1:53223_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2125597 INFO (zkCallback-3259-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2125598 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2125678 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2125685 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2125685 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2125688 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001/cores
[junit4] 2> 2125688 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:48520_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2125689 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 2125690 INFO
(OverseerStateUpdate-98139950568046597-127.0.0.1:53223_-n_0000000000)
[n:127.0.0.1:53223_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard2
[junit4] 2> 2125793 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2126705 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 2126721 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2126858 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 2126871 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 2126871 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ 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@5f749b6d
[junit4] 2> 2126872 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2126872 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 2126874 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=30,
maxMergedSegmentMB=8.55078125, floorSegmentMB=0.9296875,
forceMergeDeletesPctAllowed=16.954813802468983, segmentsPerTier=23.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2126877 WARN
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ 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> 2126923 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2126923 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2126924 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2126924 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2126925 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=45,
maxMergedSegmentMB=64.1650390625, floorSegmentMB=1.814453125,
forceMergeDeletesPctAllowed=25.168749597198776, segmentsPerTier=33.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2126925 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@15ee57be[collection1] main]
[junit4] 2> 2126927 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2126927 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2126928 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 2126930 INFO
(searcherExecutor-8076-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@15ee57be[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2126930 INFO
(coreLoadExecutor-8075-thread-1-processing-n:127.0.0.1:48520_)
[n:127.0.0.1:48520_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1570239211686068224
[junit4] 2> 2126937 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 2126937 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ 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> 2126937 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:48520/collection1/
[junit4] 2> 2126937 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2126937 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:48520/collection1/ has no
replicas
[junit4] 2> 2126937 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 2126946 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:48520/collection1/ shard2
[junit4] 2> 2127050 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2127098 INFO
(coreZkRegister-8070-thread-1-processing-n:127.0.0.1:48520_ x:collection1
c:collection1) [n:127.0.0.1:48520_ c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2127202 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2127335 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001/cores/collection1
[junit4] 2> 2127336 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001
of type NRT
[junit4] 2> 2127338 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2127339 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7bb6ce90{/,null,AVAILABLE}
[junit4] 2> 2127340 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@68433a1b{SSL,[ssl,
http/1.1]}{127.0.0.1:40610}
[junit4] 2> 2127340 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server Started @2130314ms
[junit4] 2> 2127340 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/, hostPort=40610,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001/cores}
[junit4] 2> 2127340 ERROR
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2127340 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 2127340 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2127341 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2127341 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-15T03:20:07.210Z
[junit4] 2> 2127399 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2127399 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001/solr.xml
[junit4] 2> 2127406 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2127409 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49927/solr
[junit4] 2> 2127420 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2127422 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2127424 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40610_
[junit4] 2> 2127426 INFO (zkCallback-3259-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2127426 INFO
(zkCallback-3255-thread-2-processing-n:127.0.0.1:53223_) [n:127.0.0.1:53223_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2127426 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2127434 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2127518 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2127525 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2127525 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2127527 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001/cores
[junit4] 2> 2127527 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:40610_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2127530 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 2127532 INFO
(OverseerStateUpdate-98139950568046597-127.0.0.1:53223_-n_0000000000)
[n:127.0.0.1:53223_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2127634 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2127634 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2128547 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 2128562 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2128704 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 2128717 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 2128717 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr.core.collection1.shard1.core_node2' (registry
'solr.core.collection1.shard1.core_node2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2128718 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2128718 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 2128720 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=30,
maxMergedSegmentMB=8.55078125, floorSegmentMB=0.9296875,
forceMergeDeletesPctAllowed=16.954813802468983, segmentsPerTier=23.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2128724 WARN
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ 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> 2128778 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2128778 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2128779 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2128779 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2128780 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=45,
maxMergedSegmentMB=64.1650390625, floorSegmentMB=1.814453125,
forceMergeDeletesPctAllowed=25.168749597198776, segmentsPerTier=33.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2128781 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@53ed682c[collection1] main]
[junit4] 2> 2128782 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2128783 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2128783 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 2128784 INFO
(coreLoadExecutor-8086-thread-1-processing-n:127.0.0.1:40610_)
[n:127.0.0.1:40610_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1570239213630128128
[junit4] 2> 2128788 INFO
(searcherExecutor-8087-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@53ed682c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2128793 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 2128794 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 2128794 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:40610/collection1/
[junit4] 2> 2128794 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2128794 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:40610/collection1/ has no
replicas
[junit4] 2> 2128794 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 2128798 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:40610/collection1/ shard1
[junit4] 2> 2128901 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2128901 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2128950 INFO
(coreZkRegister-8081-thread-1-processing-n:127.0.0.1:40610_ x:collection1
c:collection1) [n:127.0.0.1:40610_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2129054 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2129054 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 2129180 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001/cores/collection1
[junit4] 2> 2129181 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001
of type NRT
[junit4] 2> 2129182 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2129184 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5a7f274{/,null,AVAILABLE}
[junit4] 2> 2129184 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@38ef7240{SSL,[ssl,
http/1.1]}{127.0.0.1:49919}
[junit4] 2> 2129185 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server Started @2132159ms
[junit4] 2> 2129185 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/, hostPort=49919,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001/cores}
[junit4] 2> 2129185 ERROR
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2129186 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 2129186 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2129186 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2129186 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-15T03:20:09.055Z
[junit4] 2> 2129189 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2129189 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001/solr.xml
[junit4] 2> 2129194 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2129203 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49927/solr
[junit4] 2> 2129215 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 2129217 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2129219 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49919_
[junit4] 2> 2129221 INFO
(zkCallback-3255-thread-1-processing-n:127.0.0.1:53223_) [n:127.0.0.1:53223_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2129221 INFO (zkCallback-3259-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2129221 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2129221 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2129230 INFO
(zkCallback-3277-thread-1-processing-n:127.0.0.1:49919_) [n:127.0.0.1:49919_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2129314 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2129321 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2129321 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2129324 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001/cores
[junit4] 2> 2129324 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:49919_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2129326 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 2129327 INFO
(OverseerStateUpdate-98139950568046597-127.0.0.1:53223_-n_0000000000)
[n:127.0.0.1:53223_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard2
[junit4] 2> 2129430 INFO
(zkCallback-3277-thread-1-processing-n:127.0.0.1:49919_) [n:127.0.0.1:49919_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 2129430 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 2129430 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 2130343 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 2130383 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2130525 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 2130537 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 2130537 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr.core.collection1.shard2.core_node3' (registry
'solr.core.collection1.shard2.core_node3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2130538 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2130538 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 2130540 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=30,
maxMergedSegmentMB=8.55078125, floorSegmentMB=0.9296875,
forceMergeDeletesPctAllowed=16.954813802468983, segmentsPerTier=23.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2130550 WARN
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ 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> 2130601 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2130601 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2130602 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2130602 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2130603 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=45,
maxMergedSegmentMB=64.1650390625, floorSegmentMB=1.814453125,
forceMergeDeletesPctAllowed=25.168749597198776, segmentsPerTier=33.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2130603 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@208bfa96[collection1] main]
[junit4] 2> 2130605 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 2130605 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2130606 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 2130606 INFO
(coreLoadExecutor-8097-thread-1-processing-n:127.0.0.1:49919_)
[n:127.0.0.1:49919_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1570239215540633600
[junit4] 2> 2130607 INFO
(searcherExecutor-8098-thread-1-processing-n:127.0.0.1:49919_ x:collection1
c:collection1) [n:127.0.0.1:49919_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@208bfa96[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2130611 INFO
(coreZkRegister-8092-thread-1-processing-n:127.0.0.1:49919_ x:collection1
c:collection1) [n:127.0.0.1:49919_ c:collection1 s:shard2 r:core_node3
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 2130623 INFO
(updateExecutor-3274-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 2130630 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 2130630 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 2130630 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 2130630 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2130630 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core
[collection1] as recovering, leader is [https://127.0.0.1:48520/collection1/]
and I am [https://127.0.0.1:49919/collection1/]
[junit4] 2> 2130633 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [https://127.0.0.1:48520]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:49919_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 2130670 INFO (qtp1898631567-23888) [n:127.0.0.1:48520_ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true,
maxTime: 183 s
[junit4] 2> 2130671 INFO (qtp1898631567-23888) [n:127.0.0.1:48520_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:49919_, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"https://127.0.0.1:49919","node_name":"127.0.0.1:49919_","state":"down","type":"NRT"}
[junit4] 2> 2130734 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 2130734 INFO
(zkCallback-3277-thread-1-processing-n:127.0.0.1:49919_) [n:127.0.0.1:49919_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 2130734 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 2130983 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-4-001/cores/collection1
[junit4] 2> 2130984 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-4-001
of type NRT
[junit4] 2> 2130986 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2130987 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7fc423b0{/,null,AVAILABLE}
[junit4] 2> 2130988 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@2e1afcdd{SSL,[ssl,
http/1.1]}{127.0.0.1:53172}
[junit4] 2> 2130994 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.Server Started @2133969ms
[junit4] 2> 2130995 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/tempDir-001/jetty4,
replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=53172,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-4-001/cores}
[junit4] 2> 2130995 ERROR
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2130995 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 2130995 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2130995 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2130995 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-15T03:20:10.864Z
[junit4] 2> 2130999 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 2130999 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-4-001/solr.xml
[junit4] 2> 2131004 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2131007 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49927/solr
[junit4] 2> 2131017 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 2131019 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2131022 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53172_
[junit4] 2> 2131024 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2131024 INFO (zkCallback-3259-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2131024 INFO
(zkCallback-3255-thread-1-processing-n:127.0.0.1:53223_) [n:127.0.0.1:53223_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2131024 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2131024 INFO
(zkCallback-3277-thread-1-processing-n:127.0.0.1:49919_) [n:127.0.0.1:49919_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2131026 INFO
(zkCallback-3284-thread-1-processing-n:127.0.0.1:53172_) [n:127.0.0.1:53172_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2131130 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2131138 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2131138 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5f749b6d
[junit4] 2> 2131140 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001/shard-4-001/cores
[junit4] 2> 2131140 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27])
[n:127.0.0.1:53172_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2131142 INFO
(coreLoadExecutor-8108-thread-1-processing-n:127.0.0.1:53172_)
[n:127.0.0.1:53172_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 2131143 INFO
(OverseerStateUpdate-98139950568046597-127.0.0.1:53223_-n_0000000000)
[n:127.0.0.1:53223_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 2131246 INFO
(zkCallback-3284-thread-1-processing-n:127.0.0.1:53172_) [n:127.0.0.1:53172_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 2131246 INFO
(zkCallback-3271-thread-1-processing-n:127.0.0.1:40610_) [n:127.0.0.1:40610_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 2131246 INFO
(zkCallback-3277-thread-1-processing-n:127.0.0.1:49919_) [n:127.0.0.1:49919_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 2131246 INFO
(zkCallback-3265-thread-1-processing-n:127.0.0.1:48520_) [n:127.0.0.1:48520_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 2131671 INFO (qtp1898631567-23888) [n:127.0.0.1:48520_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:49919_, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"https://127.0.0.1:49919","node_name":"127.0.0.1:49919_","state":"recovering","type":"NRT"}
[junit4] 2> 2131671 INFO (qtp1898631567-23888) [n:127.0.0.1:48520_ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 2131672 INFO (qtp1898631567-23888) [n:127.0.0.1:48520_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:49919_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1001
[junit4] 2> 2132158 INFO
(coreLoadExecutor-8108-thread-1-processing-n:127.0.0.1:53172_)
[n:127.0.0.1:53172_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 2132173 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Attempting to PeerSync
from [https://127.0.0.1:48520/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 2132173 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.PeerSync PeerSync: core=collection1
url=https://127.0.0.1:49919 START
replicas=[https://127.0.0.1:48520/collection1/] nUpdates=100
[junit4] 2> 2132174 INFO
(coreLoadExecutor-8108-thread-1-processing-n:127.0.0.1:53172_)
[n:127.0.0.1:53172_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 2132177 INFO (qtp1898631567-23889) [n:127.0.0.1:48520_
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 2132177 INFO (qtp1898631567-23889) [n:127.0.0.1:48520_
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp= path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.IndexFingerprint IndexFingerprint
millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.PeerSync We are already in sync. No need to
do a PeerSync
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted
changes. Skipping IW.commit.
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy PeerSync stage of recovery
was successful.
[junit4] 2> 2132178 INFO
(recoveryExecutor-3275-thread-1-processing-n:127.0.0.1:49919_ x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:49919_ c:collection1 s:shard2
r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
[...truncated too long message...]
0.1:53172_) [n:127.0.0.1:53172_ c:collection1 s:shard1 r:core_node4
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard1.core_node4, tag=546937688
[junit4] 2> 2150455 INFO
(zkCallback-3284-thread-2-processing-n:127.0.0.1:53172_) [n:127.0.0.1:53172_
c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.m.SolrMetricManager
Closing metric reporters for
registry=solr.collection.collection1.shard1.leader, tag=546937688
[junit4] 2> 2150457 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@2e1afcdd{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 2150457 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7fc423b0{/,null,UNAVAILABLE}
[junit4] 2> 2150458 ERROR
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
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> 2150459 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C3406D32319C9C27]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:49927 49927
[junit4] 2> 2150543 INFO (Thread-8065) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:49927 49927
[junit4] 2> 2150546 WARN (Thread-8065) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 5 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading
-Dtests.method=testDynamicLoading -Dtests.seed=C3406D32319C9C27
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-AR
-Dtests.timezone=Etc/GMT-10 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 26.1s J2 | TestDynamicLoading.testDynamicLoading <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected
value 'org.apache.solr.core.BlobStoreTestRequestHandler' for path
'overlay/requestHandler/\/test1/class' full output: {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "overlay":{
[junit4] > "znodeVersion":0,
[junit4] > "runtimeLib":{"colltest":{
[junit4] > "name":"colltest",
[junit4] > "version":1}}}}, from server: null
[junit4] > at
__randomizedtesting.SeedInfo.seed([C3406D32319C9C27:1B0D4065C6413987]:0)
[junit4] > at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
[junit4] > at
org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:97)
[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:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.core.TestDynamicLoading_C3406D32319C9C27-001
[junit4] 2> Jun 15, 2017 3:20:30 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1353, maxMBSortInHeap=5.634494012261458,
sim=RandomSimilarity(queryNorm=true): {}, locale=es-AR, timezone=Etc/GMT-10
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_131 (64-bit)/cpus=4,threads=1,free=88951824,total=527958016
[junit4] 2> NOTE: All tests run in this JVM:
[FieldMutatingUpdateProcessorTest, TestMacros, TestDistributedMissingSort,
TestTolerantSearch, TestExclusionRuleCollectionAccess,
DeleteInactiveReplicaTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest,
TestIBSimilarityFactory, DataDrivenBlockJoinTest, TestSchemaResource,
DeleteReplicaTest, TestPhraseSuggestions, CurrencyFieldXmlFileTest,
TestZkChroot, TestNumericTokenStream, TestCloudManagedSchema,
ChaosMonkeyNothingIsSafeTest, BadIndexSchemaTest,
DirectUpdateHandlerOptimizeTest, AliasIntegrationTest, TestWriterPerf,
LeaderElectionTest, StatsComponentTest, BaseCdcrDistributedZkTest,
TermVectorComponentDistributedTest, TestUseDocValuesAsStored2,
TestRandomCollapseQParserPlugin, SuggesterWFSTTest,
PKIAuthenticationIntegrationTest, SolrGraphiteReporterTest,
TestMaxTokenLenTokenizer, TestIndexingPerformance, TestMergePolicyConfig,
TestSolrCoreParser, TestFieldCacheReopen, TriLevelCompositeIdRoutingTest,
TestMultiValuedNumericRangeQuery, SolrCloudExampleTest,
TestFreeTextSuggestions, PrimitiveFieldTypeTest,
TestLegacyNumericRangeQueryBuilder, TestRecoveryHdfs, RecoveryZkTest,
QueryResultKeyTest, TestStressUserVersions, StatsReloadRaceTest,
AtomicUpdatesTest, TestSolrCloudWithKerberosAlt, ConnectionManagerTest,
StatelessScriptUpdateProcessorFactoryTest, TestPushWriter,
TestCorePropertiesReload, TestInPlaceUpdatesDistrib, ZkStateWriterTest,
AutoCommitTest, TestPayloadCheckQParserPlugin, TestCloudDeleteByQuery,
CoreMergeIndexesAdminHandlerTest, TestCoreDiscovery,
TestSerializedLuceneMatchVersion, TestShortCircuitedRequests,
TestStressReorder, DistributedQueueTest, ReplicaListTransformerTest,
BooleanFieldTest, TestCollationFieldDocValues, PropertiesRequestHandlerTest,
BlockJoinFacetSimpleTest, OpenExchangeRatesOrgProviderTest,
CachingDirectoryFactoryTest, SynonymTokenizerTest, TestCollapseQParserPlugin,
TestUtils, NoCacheHeaderTest, TestIndexSearcher, BJQParserTest,
TestCSVResponseWriter, DirectUpdateHandlerTest,
TestFieldTypeCollectionResource, SharedFSAutoReplicaFailoverUtilsTest,
LukeRequestHandlerTest, HLLSerializationTest, TestSolrConfigHandlerConcurrent,
TestReversedWildcardFilterFactory, OverseerModifyCollectionTest,
HdfsChaosMonkeyNothingIsSafeTest, TestDeleteCollectionOnDownNodes,
CircularListTest, TestGraphTermsQParserPlugin, TestTrackingShardHandlerFactory,
TestExtendedDismaxParser, TestSolrCLIRunExample, CdcrReplicationHandlerTest,
TestSolrQueryParser, TestDynamicFieldResource, ForceLeaderTest, CursorMarkTest,
SolrTestCaseJ4Test, TestRebalanceLeaders, DebugComponentTest,
OverseerTaskQueueTest, TestBlobHandler, TestPullReplicaErrorHandling,
DateFieldTest, HighlighterConfigTest, RequestHandlersTest,
TestLMDirichletSimilarityFactory, DistributedFacetPivotWhiteBoxTest,
DocValuesMultiTest, CoreAdminHandlerTest, TestSort, SolrMetricReporterTest,
TestMinMaxOnMultiValuedField, TestImpersonationWithHadoopAuth,
TestSimpleTextCodec, SuggesterTSTTest, BasicDistributedZk2Test,
CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest,
TestReplicationHandler, TestJoin, TestRangeQuery, SimpleFacetsTest,
TestGroupingSearch, PeerSyncTest, ShowFileRequestHandlerTest,
DistributedQueryElevationComponentTest, SolrRequestParserTest, TestTrie,
SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases,
DefaultValueUpdateProcessorTest, TestRemoteStreaming, TestSolrDeletionPolicy1,
XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestQueryTypes,
TermsComponentTest, DocumentBuilderTest, TestValueSourceCache,
MoreLikeThisHandlerTest, FastVectorHighlighterTest, LoggingHandlerTest,
IndexSchemaTest, TestCollationField, TestAnalyzedSuggestions,
BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, UpdateParamsTest,
SolrIndexConfigTest, TestDocSet, NumericFieldsTest, TestConfig,
TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, TestCharFilters,
EchoParamsTest, TestDFRSimilarityFactory, TestBM25SimilarityFactory,
ScriptEngineTest, TestFastLRUCache, PreAnalyzedFieldTest, SliceStateTest,
FileUtilsTest, TestRTGBase, CursorPagingTest, ActionThrottleTest, AssignTest,
CdcrBootstrapTest, CleanupOldIndexTest, CollectionStateFormat2Test,
CollectionsAPIAsyncDistributedZkTest, DeleteLastCustomShardedReplicaTest,
DeleteNodeTest, DistribCursorPagingTest, DistributedVersionInfoTest,
DocValuesNotIndexedTest, LeaderFailoverAfterPartitionTest,
LeaderInitiatedRecoveryOnCommitTest, LeaderInitiatedRecoveryOnShardRestartTest,
MissingSegmentRecoveryTest, MultiThreadedOCPTest, SSLMigrationTest,
SolrXmlInZkTest, TestAuthenticationFramework, TestCloudPivotFacet,
TestClusterProperties, TestConfigSetsAPIExclusivity,
TestConfigSetsAPIZkFailure, TestCryptoKeys, TestHdfsCloudBackupRestore,
TestLockTree, TestMiniSolrCloudClusterSSL, TestRequestForwarding,
TestSSLRandomization, TestSegmentSorting, TestSolrCloudWithDelegationTokens,
TestStressCloudBlindAtomicUpdates, TestTolerantUpdateProcessorCloud,
TestTolerantUpdateProcessorRandomCloud,
VMParamsZkACLAndCredentialsProvidersTest, AutoScalingHandlerTest,
TestPolicyCloud, HdfsRecoverLeaseTest, HdfsRestartWhileUpdatingTest,
HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsTlogReplayBufferedWhileIndexingTest,
HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestConfigSetImmutable,
TestDynamicLoading]
[junit4] Completed [584/725 (1!)] on J2 in 26.88s, 1 test, 1 failure <<<
FAILURES!
[...truncated 43847 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
