Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/458/
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
Could not find collection:.system
Stack Trace:
java.lang.AssertionError: Could not find collection:.system
at
__randomizedtesting.SeedInfo.seed([C6C82A53A14A469F:1E8507045697E33F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:153)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:138)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
at
org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:110)
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:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10815 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/init-core-data-001
[junit4] 2> 234978 INFO
(SUITE-TestDynamicLoading-seed#[C6C82A53A14A469F]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 234994 INFO
(SUITE-TestDynamicLoading-seed#[C6C82A53A14A469F]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /bjz/
[junit4] 2> 235026 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 235026 INFO (Thread-1665) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 235027 INFO (Thread-1665) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 235130 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkTestServer start zk server on port:56510
[junit4] 2> 235202 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 235204 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 235216 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 235218 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 235219 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 235221 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 235222 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 235224 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 235227 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 235232 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 235239 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 235830 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/control-001/cores/collection1
[junit4] 2> 235846 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 235849 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@8626d09{/bjz,null,AVAILABLE}
[junit4] 2> 235902 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@e06bd76{SSL,[ssl,
http/1.1]}{127.0.0.1:59326}
[junit4] 2> 235902 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server Started @248803ms
[junit4] 2> 235902 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/tempDir-001/control/data,
hostContext=/bjz, hostPort=59326,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/control-001/cores}
[junit4] 2> 235902 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 235902 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 235904 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 235904 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-30T02:48:22.340Z
[junit4] 2> 236063 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 236063 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/control-001/solr.xml
[junit4] 2> 236074 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 236075 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56510/solr
[junit4] 2> 236211 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:59326_bjz ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:59326_bjz
[junit4] 2> 236213 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:59326_bjz ] o.a.s.c.Overseer Overseer
(id=96678949779341317-127.0.0.1:59326_bjz-n_0000000000) starting
[junit4] 2> 236233 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:59326_bjz ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:59326_bjz
[junit4] 2> 236236 INFO
(zkCallback-145-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 236250 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:59326_bjz ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/control-001/cores
[junit4] 2> 236256 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:59326_bjz ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 236263 INFO
(OverseerStateUpdate-96678949779341317-127.0.0.1:59326_bjz-n_0000000000)
[n:127.0.0.1:59326_bjz ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 236297 WARN
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 236304 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.3.0
[junit4] 2> 236367 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 236801 WARN
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 236819 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 236899 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 236899 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/control-001/cores/collection1/data/]
[junit4] 2> 236899 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50480fa5
[junit4] 2> 236927 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1276842811,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 237076 WARN
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 237106 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 237107 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 237107 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 237107 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 237109 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.36860115968088436]
[junit4] 2> 237111 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@11742387[collection1] main]
[junit4] 2> 237117 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 237118 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 237118 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 237119 INFO
(searcherExecutor-314-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@11742387[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 237119 INFO
(coreLoadExecutor-313-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1546863198658887680
[junit4] 2> 237147 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 237147 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
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> 237147 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:59326/bjz/collection1/
[junit4] 2> 237147 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 237147 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
https://127.0.0.1:59326/bjz/collection1/ has no replicas
[junit4] 2> 237154 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
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:59326/bjz/collection1/ shard1
[junit4] 2> 237304 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 237306 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 237306 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 237355 INFO
(coreZkRegister-306-thread-1-processing-n:127.0.0.1:59326_bjz x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:59326_bjz
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 238522 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001/cores/collection1
[junit4] 2> 238539 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001
[junit4] 2> 238541 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 238553 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@48a786e5{/bjz,null,AVAILABLE}
[junit4] 2> 238556 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@61efd985{SSL,[ssl,
http/1.1]}{127.0.0.1:60046}
[junit4] 2> 238556 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server Started @251457ms
[junit4] 2> 238556 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/bjz, hostPort=60046,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001/cores}
[junit4] 2> 238557 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 238557 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 238557 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 238557 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-30T02:48:24.993Z
[junit4] 2> 238637 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 238637 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001/solr.xml
[junit4] 2> 238662 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 238663 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56510/solr
[junit4] 2> 238924 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:60046_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 238938 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:60046_bjz ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60046_bjz
[junit4] 2> 238966 INFO
(zkCallback-145-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 238966 INFO (zkCallback-149-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 239266 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 239273 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:60046_bjz ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001/cores
[junit4] 2> 239273 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:60046_bjz ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 239285 INFO
(OverseerStateUpdate-96678949779341317-127.0.0.1:59326_bjz-n_0000000000)
[n:127.0.0.1:59326_bjz ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 239428 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 240337 WARN
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 240343 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.3.0
[junit4] 2> 240454 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 240923 WARN
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 240929 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 241006 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 241007 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 241007 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50480fa5
[junit4] 2> 241016 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1276842811,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 241023 WARN
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 241071 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 241071 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 241072 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 241072 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 241079 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.36860115968088436]
[junit4] 2> 241079 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1cbd0508[collection1] main]
[junit4] 2> 241102 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 241103 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 241103 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 241111 INFO
(coreLoadExecutor-324-thread-1-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1546863202844803072
[junit4] 2> 241139 INFO
(searcherExecutor-325-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered
new searcher Searcher@1cbd0508[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 241140 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 241140 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz 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> 241140 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:60046/bjz/collection1/
[junit4] 2> 241140 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 241140 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
https://127.0.0.1:60046/bjz/collection1/ has no replicas
[junit4] 2> 241147 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 241153 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: https://127.0.0.1:60046/bjz/collection1/ shard2
[junit4] 2> 241460 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 241514 INFO
(coreZkRegister-319-thread-1-processing-n:127.0.0.1:60046_bjz x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:60046_bjz c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 241591 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001/cores/collection1
[junit4] 2> 241592 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001
[junit4] 2> 241594 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 241598 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7b0234ff{/bjz,null,AVAILABLE}
[junit4] 2> 241604 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@43cd56cf{SSL,[ssl,
http/1.1]}{127.0.0.1:44043}
[junit4] 2> 241605 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server Started @254506ms
[junit4] 2> 241605 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/bjz, hostPort=44043,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001/cores}
[junit4] 2> 241606 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 241606 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 241606 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 241606 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-30T02:48:28.042Z
[junit4] 2> 241628 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 241712 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 241712 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001/solr.xml
[junit4] 2> 241742 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 241743 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56510/solr
[junit4] 2> 241949 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:44043_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 241968 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:44043_bjz ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44043_bjz
[junit4] 2> 241970 INFO (zkCallback-149-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 241971 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 241971 INFO
(zkCallback-145-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 241997 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 242010 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:44043_bjz ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001/cores
[junit4] 2> 242010 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:44043_bjz ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 242027 INFO
(OverseerStateUpdate-96678949779341317-127.0.0.1:59326_bjz-n_0000000000)
[n:127.0.0.1:59326_bjz ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 242155 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 242162 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 243084 WARN
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 243146 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.3.0
[junit4] 2> 243318 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 243632 WARN
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 243635 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 243722 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 243722 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 243723 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50480fa5
[junit4] 2> 243724 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1276842811,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 243792 WARN
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 243843 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 243843 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 243844 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 243844 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 243853 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.36860115968088436]
[junit4] 2> 243854 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@769196ef[collection1] main]
[junit4] 2> 243856 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 243857 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 243857 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 243871 INFO
(coreLoadExecutor-335-thread-1-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1546863205738872832
[junit4] 2> 243875 INFO
(searcherExecutor-336-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Registered
new searcher Searcher@769196ef[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 243903 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 243903 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz 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> 243903 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:44043/bjz/collection1/
[junit4] 2> 243903 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 243903 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy
https://127.0.0.1:44043/bjz/collection1/ has no replicas
[junit4] 2> 243910 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: https://127.0.0.1:44043/bjz/collection1/ shard1
[junit4] 2> 243929 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 243929 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 244068 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 244068 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 244111 INFO
(coreZkRegister-330-thread-1-processing-n:127.0.0.1:44043_bjz x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:44043_bjz c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 244218 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 244218 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 244997 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001/cores/collection1
[junit4] 2> 244998 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001
[junit4] 2> 245028 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 245095 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4800a13c{/bjz,null,AVAILABLE}
[junit4] 2> 245119 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@25de35fa{SSL,[ssl,
http/1.1]}{127.0.0.1:33495}
[junit4] 2> 245119 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server Started @258020ms
[junit4] 2> 245119 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/bjz, hostPort=33495,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001/cores}
[junit4] 2> 245120 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 245120 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 245120 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 245121 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-30T02:48:31.556Z
[junit4] 2> 245230 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 245231 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001/solr.xml
[junit4] 2> 245247 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 245267 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56510/solr
[junit4] 2> 245525 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:33495_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 245557 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:33495_bjz ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33495_bjz
[junit4] 2> 245559 INFO (zkCallback-149-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 245560 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 245581 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 245560 INFO
(zkCallback-145-thread-1-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 245660 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 245663 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 245666 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:33495_bjz ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001/cores
[junit4] 2> 245672 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:33495_bjz ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 245680 INFO
(OverseerStateUpdate-96678949779341317-127.0.0.1:59326_bjz-n_0000000000)
[n:127.0.0.1:59326_bjz ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 245695 INFO
(zkCallback-167-thread-3-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 245785 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 245786 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 245787 INFO
(zkCallback-167-thread-3-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz ] 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> 246738 WARN
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 246756 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.3.0
[junit4] 2> 246803 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 247153 WARN
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 247188 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 247258 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 247267 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 247267 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50480fa5
[junit4] 2> 247279 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1276842811,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 247292 WARN
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 247363 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 247364 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 247365 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 247365 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 247366 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.36860115968088436]
[junit4] 2> 247367 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@304e5d95[collection1] main]
[junit4] 2> 247372 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 247372 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 247373 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 247374 INFO
(coreLoadExecutor-346-thread-1-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1546863209412034560
[junit4] 2> 247392 INFO
(searcherExecutor-347-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.SolrCore [collection1] Registered
new searcher Searcher@304e5d95[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 247394 INFO
(coreZkRegister-341-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 247402 INFO
(updateExecutor-164-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running
recovery
[junit4] 2> 247411 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 247415 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy ######
startupVersions=[[]]
[junit4] 2> 247415 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering
updates. core=[collection1]
[junit4] 2> 247415 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz 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> 247415 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz 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:60046/bjz/collection1/] and I am
[https://127.0.0.1:33495/bjz/collection1/]
[junit4] 2> 247424 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 247424 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 247424 INFO
(zkCallback-167-thread-3-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz ] 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> 247428 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Sending prep
recovery command to [https://127.0.0.1:60046/bjz]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:33495_bjz&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 247922 INFO (qtp572213095-2378) [n:127.0.0.1:60046_bjz ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 247927 INFO (qtp572213095-2378) [n:127.0.0.1:60046_bjz ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard2 of collection1) have state: recovering
[junit4] 2> 247927 INFO (qtp572213095-2378) [n:127.0.0.1:60046_bjz ]
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:33495_bjz, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"https://127.0.0.1:33495/bjz","node_name":"127.0.0.1:33495_bjz","state":"recovering"}
[junit4] 2> 247928 INFO (qtp572213095-2378) [n:127.0.0.1:60046_bjz ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 0 seconds.
[junit4] 2> 247928 INFO (qtp572213095-2378) [n:127.0.0.1:60046_bjz ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:33495_bjz&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 248135 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001/cores/collection1
[junit4] 2> 248136 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001
[junit4] 2> 248138 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 248212 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@39569c61{/bjz,null,AVAILABLE}
[junit4] 2> 248215 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.ServerConnector Started ServerConnector@450efce4{SSL,[ssl,
http/1.1]}{127.0.0.1:38166}
[junit4] 2> 248215 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.e.j.s.Server Started @261116ms
[junit4] 2> 248215 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/tempDir-001/jetty4,
solrconfig=solrconfig.xml, hostContext=/bjz, hostPort=38166,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001/cores}
[junit4] 2> 248215 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 248215 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 248216 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 248216 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-30T02:48:34.652Z
[junit4] 2> 248269 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 248269 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001/solr.xml
[junit4] 2> 248286 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 248287 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56510/solr
[junit4] 2> 248333 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:38166_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (4)
[junit4] 2> 248349 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:38166_bjz ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38166_bjz
[junit4] 2> 248363 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 248420 INFO
(zkCallback-174-thread-2-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 248372 INFO
(zkCallback-167-thread-3-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 248371 INFO
(zkCallback-145-thread-2-processing-n:127.0.0.1:59326_bjz)
[n:127.0.0.1:59326_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 248364 INFO (zkCallback-149-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 248364 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 248424 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:38166_bjz ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001/cores
[junit4] 2> 248424 INFO
(TEST-TestDynamicLoading.testDynamicLoading-seed#[C6C82A53A14A469F])
[n:127.0.0.1:38166_bjz ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 248447 INFO
(OverseerStateUpdate-96678949779341317-127.0.0.1:59326_bjz-n_0000000000)
[n:127.0.0.1:59326_bjz ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 248458 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Attempting to
PeerSync from [https://127.0.0.1:60046/bjz/collection1/] -
recoveringAfterStartup=[true]
[junit4] 2> 248459 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.u.PeerSync PeerSync:
core=collection1 url=https://127.0.0.1:33495/bjz START
replicas=[https://127.0.0.1:60046/bjz/collection1/] nUpdates=100
[junit4] 2> 248551 INFO
(zkCallback-167-thread-3-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz ] 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> 248552 INFO
(zkCallback-174-thread-2-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz ] 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> 248555 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 248555 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 248573 INFO (qtp572213095-2376) [n:127.0.0.1:60046_bjz
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> 248573 INFO (qtp572213095-2376) [n:127.0.0.1:60046_bjz
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp=/bjz path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=8
[junit4] 2> 248581 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz 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> 248581 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz 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> 248581 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz 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> 248581 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2 No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 248587 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 248587 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy PeerSync stage of
recovery was successful.
[junit4] 2> 248587 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Replaying updates
buffered during PeerSync.
[junit4] 2> 248587 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 248587 INFO
(recoveryExecutor-165-thread-1-processing-n:127.0.0.1:33495_bjz x:collection1
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:33495_bjz c:collection1
s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy Registering as
Active after recovery.
[junit4] 2> 248693 INFO
(zkCallback-155-thread-2-processing-n:127.0.0.1:60046_bjz)
[n:127.0.0.1:60046_bjz ] 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> 248693 INFO
(zkCallback-167-thread-3-processing-n:127.0.0.1:33495_bjz)
[n:127.0.0.1:33495_bjz ] 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> 248693 INFO
(zkCallback-174-thread-2-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz ] 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> 248694 INFO
(zkCallback-161-thread-2-processing-n:127.0.0.1:44043_bjz)
[n:127.0.0.1:44043_bjz ] 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> 249474 WARN
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 249475 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.3.0
[junit4] 2> 249532 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 250199 WARN
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 250202 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 250480 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 250481 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading_C6C82A53A14A469F-001/shard-4-001/cores/collection1/data/]
[junit4] 2> 250481 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@50480fa5
[junit4] 2> 250508 INFO
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=1276842811,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 250577 WARN
(coreLoadExecutor-357-thread-1-processing-n:127.0.0.1:38166_bjz)
[n:127.0.0.1:38166_bjz c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.RequestHandlers INVA
[...truncated too long message...]
ptor-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/hadoop-common-2.7.2-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/hadoop-hdfs-2.7.2-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/hadoop-minikdc-2.7.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.3.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.1.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_102/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar
com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160930_024413_424.events
@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160930_024413_424.suites
-stdin
[junit4] ERROR: JVM J2 ended with an exception: Forked process returned with
error code: 1. Very likely a JVM crash. See process stdout at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160930_024413_424.sysout
See process stderr at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/temp/junit4-J2-20160930_024413_424.syserr
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1481)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] at java.lang.Thread.run(Thread.java:745)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:763: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:707: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:252:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:536:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1443:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:984:
At least one slave process threw an exception, first: Forked process returned
with error code: 1. Very likely a JVM crash. See process stdout at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/temp/junit4-J0-20160930_024413_423.sysout
See process stderr at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/temp/junit4-J0-20160930_024413_423.syserr
Total time: 108 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
