Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1319/
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.StressHdfsTest
Error Message:
ObjectTracker found 2 object(s) that were not released!!! [NRTCachingDirectory,
NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:361) at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3015)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1579) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:994) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:855) at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:979) at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611) at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266) at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:361) at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3015)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1579) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:994) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:855) at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:979) at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611) at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266) at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 2 object(s) that were not
released!!! [NRTCachingDirectory, NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory
at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:361)
at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3015)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1579)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:994)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:855)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:979)
at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611)
at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory
at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:361)
at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3015)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1579)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:994)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:855)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:979)
at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611)
at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([5FC01C2D33532281]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:295)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 13178 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.StressHdfsTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/init-core-data-001
[junit4] 2> 4360669 WARN
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=42 numCloses=42
[junit4] 2> 4360669 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 4360671 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 4360671 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 4360722 WARN
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 4360730 WARN
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4360732 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 4360749 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_33276_hdfs____7d5m8r/webapp
[junit4] 2> 4362146 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33276
[junit4] 2> 4362232 WARN
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4362233 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 4362245 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_56136_datanode____qmkl8a/webapp
[junit4] 2> 4362636 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:56136
[junit4] 2> 4362697 WARN
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4362699 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 4362712 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_40313_datanode____.60gphc/webapp
[junit4] 2> 4362755 INFO (IPC Server handler 4 on 44131) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-f3a4ed9f-f395-4fba-a1a2-3bbe54989399 node
DatanodeRegistration(127.0.0.1:57767,
datanodeUuid=46cdda94-49d7-4e8f-b067-8c27c24fbe79, infoPort=40243,
infoSecurePort=0, ipcPort=43596,
storageInfo=lv=-56;cid=testClusterID;nsid=391903592;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 4362756 INFO (IPC Server handler 4 on 44131) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-299a82f0-dd14-49ef-a458-9d15cf1185e5 node
DatanodeRegistration(127.0.0.1:57767,
datanodeUuid=46cdda94-49d7-4e8f-b067-8c27c24fbe79, infoPort=40243,
infoSecurePort=0, ipcPort=43596,
storageInfo=lv=-56;cid=testClusterID;nsid=391903592;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 4363143 INFO
(SUITE-StressHdfsTest-seed#[5FC01C2D33532281]-worker) [ ] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40313
[junit4] 2> 4363254 INFO (IPC Server handler 2 on 44131) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-8166f56d-0b2a-4b08-b1ce-1c8f72c2f077 node
DatanodeRegistration(127.0.0.1:60595,
datanodeUuid=89f31a2d-c4db-4469-91e3-5428c308cbe3, infoPort=55399,
infoSecurePort=0, ipcPort=52404,
storageInfo=lv=-56;cid=testClusterID;nsid=391903592;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 4363255 INFO (IPC Server handler 2 on 44131) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-5a89b1b7-79a7-4e5b-b22a-75f2a0f7a5d4 node
DatanodeRegistration(127.0.0.1:60595,
datanodeUuid=89f31a2d-c4db-4469-91e3-5428c308cbe3, infoPort=55399,
infoSecurePort=0, ipcPort=52404,
storageInfo=lv=-56;cid=testClusterID;nsid=391903592;c=0), blocks: 0,
hasStaleStorage: false, processing time: 1 msecs
[junit4] 2> 4363314 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ZkTestServer
STARTING ZK TEST SERVER
[junit4] 2> 4363314 INFO (Thread-31519) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4363314 INFO (Thread-31519) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 4363316 ERROR (Thread-31519) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 4363414 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ZkTestServer
start zk server on port:45162
[junit4] 2> 4363420 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c889873fc0000, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 4363427 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 4363429 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 4363431 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4363432 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 4363434 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 4363435 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 4363437 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 4363438 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4363440 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4363441 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 4363443 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 4363446 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly
asked otherwise
[junit4] 2> 4363559 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.SolrTestCaseJ4
Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/control-001/cores/collection1
[junit4] 2> 4363560 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 4363564 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7c57277e{/,null,AVAILABLE}
[junit4] 2> 4363566 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1704b7a8{HTTP/1.1,[http/1.1]}{127.0.0.1:33100}
[junit4] 2> 4363566 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
Started @4367094ms
[junit4] 2> 4363566 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:44131/hdfs__localhost_44131__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001_tempDir-002_control_data,
replicaType=NRT, hostContext=/, hostPort=33100,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/control-001/cores}
[junit4] 2> 4363566 ERROR
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4363566 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 4363566 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4363567 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4363574 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T16:45:11.806Z
[junit4] 2> 4363577 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4363577 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/control-001/solr.xml
[junit4] 2> 4363582 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9, but no JMX
reporters were configured - adding default JMX reporter.
[junit4] 2> 4363586 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:45162/solr
[junit4] 2> 4363613 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4363614 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33100_
[junit4] 2> 4363615 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.c.Overseer Overseer (id=98103480257609732-127.0.0.1:33100_-n_0000000000)
starting
[junit4] 2> 4363623 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33100_
[junit4] 2> 4363628 INFO
(zkCallback-30338-thread-1-processing-n:127.0.0.1:33100_) [n:127.0.0.1:33100_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4363698 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4363705 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4363705 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4363707 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/control-001/cores
[junit4] 2> 4363707 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:33100_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4363708 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4363715 INFO
(OverseerStateUpdate-98103480257609732-127.0.0.1:33100_-n_0000000000)
[n:127.0.0.1:33100_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 4363732 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 4363759 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 4363884 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4363902 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection, trusted=true
[junit4] 2> 4363902 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.core_node1' (registry
'solr.core.control_collection.shard1.core_node1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4363902 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:44131/solr_hdfs_home
[junit4] 2> 4363902 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 4363902 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4363902 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/control-001/cores/collection1],
dataDir=[hdfs://localhost:44131/solr_hdfs_home/control_collection/core_node1/data/]
[junit4] 2> 4363904 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/control_collection/core_node1/data/snapshot_metadata
[junit4] 2> 4363911 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4363911 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 4363912 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 4363918 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4363919 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 4363935 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 4363941 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 4363941 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 4363941 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 4363947 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 4363947 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=19, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=70.68359375,
floorSegmentMB=0.595703125, forceMergeDeletesPctAllowed=9.843885925992309,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.32485238773878367
[junit4] 2> 4363959 INFO (IPC Server handler 6 on 44131) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57767 is
added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-8166f56d-0b2a-4b08-b1ce-1c8f72c2f077:NORMAL:127.0.0.1:60595|RBW],
ReplicaUC[[DISK]DS-f3a4ed9f-f395-4fba-a1a2-3bbe54989399:NORMAL:127.0.0.1:57767|FINALIZED]]}
size 0
[junit4] 2> 4363960 INFO (IPC Server handler 7 on 44131) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60595 is
added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-f3a4ed9f-f395-4fba-a1a2-3bbe54989399:NORMAL:127.0.0.1:57767|FINALIZED],
ReplicaUC[[DISK]DS-5a89b1b7-79a7-4e5b-b22a-75f2a0f7a5d4:NORMAL:127.0.0.1:60595|FINALIZED]]}
size 0
[junit4] 2> 4363964 WARN
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 4364012 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 4364012 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4364012 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 4364022 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 4364022 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 4364024 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=668559482,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 4364028 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@57fc6a41[collection1] main]
[junit4] 2> 4364029 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4364030 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4364030 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 4364032 INFO
(searcherExecutor-16651-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@57fc6a41[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4364032 INFO
(coreLoadExecutor-16650-thread-1-processing-n:127.0.0.1:33100_)
[n:127.0.0.1:33100_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569655684873584640
[junit4] 2> 4364038 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 4364038 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ 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> 4364038 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33100/collection1/
[junit4] 2> 4364038 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 4364038 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:33100/collection1/ has no replicas
[junit4] 2> 4364039 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 4364042 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:33100/collection1/ shard1
[junit4] 2> 4364193 INFO
(coreZkRegister-16643-thread-1-processing-n:127.0.0.1:33100_ x:collection1
c:control_collection) [n:127.0.0.1:33100_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 4364231 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4364232 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45162/solr ready
[junit4] 2> 4364232 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ChaosMonkey
monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 4364232 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 4364345 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.SolrTestCaseJ4
Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-1-001/cores/collection1
[junit4] 2> 4364346 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-1-001
of type TLOG
[junit4] 2> 4364347 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 4364349 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4275dcef{/,null,AVAILABLE}
[junit4] 2> 4364350 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@434a2bc6{HTTP/1.1,[http/1.1]}{127.0.0.1:45981}
[junit4] 2> 4364350 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
Started @4367878ms
[junit4] 2> 4364350 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:44131/hdfs__localhost_44131__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001_tempDir-002_jetty1,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/, hostPort=45981,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-1-001/cores}
[junit4] 2> 4364350 ERROR
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4364351 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 4364351 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4364351 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4364351 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T16:45:12.584Z
[junit4] 2> 4364355 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c889873fc0007, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 4364355 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4364355 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-1-001/solr.xml
[junit4] 2> 4364361 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9, but no JMX
reporters were configured - adding default JMX reporter.
[junit4] 2> 4364365 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:45162/solr
[junit4] 2> 4364377 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4364381 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4364383 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45981_
[junit4] 2> 4364384 INFO (zkCallback-30342-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4364385 INFO
(zkCallback-30338-thread-2-processing-n:127.0.0.1:33100_) [n:127.0.0.1:33100_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4364386 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4364462 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4364469 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4364470 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4364473 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-1-001/cores
[junit4] 2> 4364473 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:45981_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4364474 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4364476 INFO
(OverseerStateUpdate-98103480257609732-127.0.0.1:33100_-n_0000000000)
[n:127.0.0.1:33100_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 4364578 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] 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> 4365494 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 4365509 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 4365635 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 4365649 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 4365649 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr.core.collection1.shard1.core_node1' (registry
'solr.core.collection1.shard1.core_node1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4365649 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:44131/solr_hdfs_home
[junit4] 2> 4365649 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Solr Kerberos Authentication disabled
[junit4] 2> 4365650 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4365650 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-1-001/cores/collection1],
dataDir=[hdfs://localhost:44131/solr_hdfs_home/collection1/core_node1/data/]
[junit4] 2> 4365651 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node1/data/snapshot_metadata
[junit4] 2> 4365671 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 4365671 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 4365671 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Creating new single instance HDFS BlockCache
[junit4] 2> 4366849 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.SolrCore
[collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@440b8e59
[junit4] 2> 4366849 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node1/data
[junit4] 2> 4366859 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.m.SolrMetricManager
Closing metric reporters for registry=solr.core.collection1.shard1.core_node1,
tag=1141608025
[junit4] 2> 4366860 INFO
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.m.SolrMetricManager
Closing metric reporters for
registry=solr.collection.collection1.shard1.leader, tag=1141608025
[junit4] 2> 4366860 ERROR
(coreLoadExecutor-16661-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ c:collection1 x:collection1] o.a.s.c.SolrCore Error while
closing
[junit4] 2> java.lang.NullPointerException
[junit4] 2> at
org.apache.solr.core.SolrCore.close(SolrCore.java:1604)
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:994)
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:855)
[junit4] 2> at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:979)
[junit4] 2> at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611)
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 4366861 ERROR
(coreContainerWorkExecutor-16657-thread-1-processing-n:127.0.0.1:45981_)
[n:127.0.0.1:45981_ ] o.a.s.c.CoreContainer Error waiting for SolrCore to be
created
[junit4] 2> java.util.concurrent.ExecutionException:
org.apache.solr.common.SolrException: Unable to create core [collection1]
[junit4] 2> at
java.util.concurrent.FutureTask.report(FutureTask.java:122)
[junit4] 2> at
java.util.concurrent.FutureTask.get(FutureTask.java:192)
[junit4] 2> at
org.apache.solr.core.CoreContainer.lambda$load$8(CoreContainer.java:639)
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unable to
create core [collection1]
[junit4] 2> at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:994)
[junit4] 2> at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611)
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
[junit4] 2> ... 5 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: The max
direct memory is likely too low. Either increase it (by adding
-XX:MaxDirectMemorySize=<size>g -XX:+UseLargePages to your containers startup
args) or disable direct allocation using
solr.hdfs.blockcache.direct.memory.allocation=false in solrconfig.xml. If you
are putting the block cache on the heap, your java heap size might not be large
enough. Failed allocating ~8.388608 MB.
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:1002)
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:855)
[junit4] 2> at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:979)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: java.lang.RuntimeException: The max direct memory
is likely too low. Either increase it (by adding
-XX:MaxDirectMemorySize=<size>g -XX:+UseLargePages to your containers startup
args) or disable direct allocation using
solr.hdfs.blockcache.direct.memory.allocation=false in solrconfig.xml. If you
are putting the block cache on the heap, your java heap size might not be large
enough. Failed allocating ~8.388608 MB.
[junit4] 2> at
org.apache.solr.core.HdfsDirectoryFactory.createBlockCache(HdfsDirectoryFactory.java:311)
[junit4] 2> at
org.apache.solr.core.HdfsDirectoryFactory.getBlockDirectoryCache(HdfsDirectoryFactory.java:287)
[junit4] 2> at
org.apache.solr.core.HdfsDirectoryFactory.create(HdfsDirectoryFactory.java:227)
[junit4] 2> at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:346)
[junit4] 2> at
org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:490)
[junit4] 2> at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:942)
[junit4] 2> ... 9 more
[junit4] 2> Caused by: java.lang.OutOfMemoryError: Direct buffer memory
[junit4] 2> at java.nio.Bits.reserveMemory(Bits.java:693)
[junit4] 2> at
java.nio.DirectByteBuffer.<init>(DirectByteBuffer.java:123)
[junit4] 2> at
java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:311)
[junit4] 2> at
org.apache.solr.store.blockcache.BlockCache.<init>(BlockCache.java:71)
[junit4] 2> at
org.apache.solr.core.HdfsDirectoryFactory.createBlockCache(HdfsDirectoryFactory.java:309)
[junit4] 2> ... 14 more
[junit4] 2> 4367449 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.SolrTestCaseJ4
Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-2-001/cores/collection1
[junit4] 2> 4367450 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-2-001
of type TLOG
[junit4] 2> 4367451 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 4367452 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@115e0238{/,null,AVAILABLE}
[junit4] 2> 4367453 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4922b8a8{HTTP/1.1,[http/1.1]}{127.0.0.1:43980}
[junit4] 2> 4367454 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
Started @4370982ms
[junit4] 2> 4367454 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:44131/hdfs__localhost_44131__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001_tempDir-002_jetty2,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/, hostPort=43980,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-2-001/cores}
[junit4] 2> 4367455 ERROR
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4367455 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 4367455 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4367455 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4367455 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T16:45:15.688Z
[junit4] 2> 4367458 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4367458 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-2-001/solr.xml
[junit4] 2> 4367463 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9, but no JMX
reporters were configured - adding default JMX reporter.
[junit4] 2> 4367470 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:45162/solr
[junit4] 2> 4367481 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4367485 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4367490 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43980_
[junit4] 2> 4367493 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4367493 INFO (zkCallback-30342-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4367494 INFO
(zkCallback-30354-thread-1-processing-n:127.0.0.1:43980_) [n:127.0.0.1:43980_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4367502 INFO
(zkCallback-30338-thread-2-processing-n:127.0.0.1:33100_) [n:127.0.0.1:33100_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4367568 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4367576 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4367576 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4367579 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-2-001/cores
[junit4] 2> 4367579 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:43980_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4367580 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4367581 INFO
(OverseerStateUpdate-98103480257609732-127.0.0.1:33100_-n_0000000000)
[n:127.0.0.1:33100_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 4367582 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] 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> 4367604 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 4367619 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 4367744 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 4367761 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 4367761 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr.core.collection1.shard1.core_node2' (registry
'solr.core.collection1.shard1.core_node2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4367762 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:44131/solr_hdfs_home
[junit4] 2> 4367762 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Solr Kerberos Authentication disabled
[junit4] 2> 4367762 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4367762 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-2-001/cores/collection1],
dataDir=[hdfs://localhost:44131/solr_hdfs_home/collection1/core_node2/data/]
[junit4] 2> 4367763 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node2/data/snapshot_metadata
[junit4] 2> 4367771 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 4367771 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 4367771 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Creating new single instance HDFS BlockCache
[junit4] 2> 4368034 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 4368035 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node2/data
[junit4] 2> 4368056 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node2/data/index
[junit4] 2> 4368062 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 4368062 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 4368062 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Creating new single instance HDFS BlockCache
[junit4] 2> 4368066 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 4368066 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=25,
maxMergedSegmentMB=70.68359375, floorSegmentMB=0.595703125,
forceMergeDeletesPctAllowed=9.843885925992309, segmentsPerTier=19.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32485238773878367
[junit4] 2> 4368077 INFO (IPC Server handler 5 on 44131) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57767 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5a89b1b7-79a7-4e5b-b22a-75f2a0f7a5d4:NORMAL:127.0.0.1:60595|RBW],
ReplicaUC[[DISK]DS-299a82f0-dd14-49ef-a458-9d15cf1185e5:NORMAL:127.0.0.1:57767|RBW]]}
size 0
[junit4] 2> 4368078 INFO (IPC Server handler 9 on 44131) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60595 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-299a82f0-dd14-49ef-a458-9d15cf1185e5:NORMAL:127.0.0.1:57767|RBW],
ReplicaUC[[DISK]DS-8166f56d-0b2a-4b08-b1ce-1c8f72c2f077:NORMAL:127.0.0.1:60595|FINALIZED]]}
size 0
[junit4] 2> 4368083 WARN
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 4368130 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 4368130 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4368130 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 4368141 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 4368141 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 4368144 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=668559482,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 4368148 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@7da0c83e[collection1] main]
[junit4] 2> 4368149 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4368150 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4368151 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 4368152 INFO
(searcherExecutor-16669-thread-1-processing-n:127.0.0.1:43980_ x:collection1
c:collection1) [n:127.0.0.1:43980_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7da0c83e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4368152 INFO
(coreLoadExecutor-16668-thread-1-processing-n:127.0.0.1:43980_)
[n:127.0.0.1:43980_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1569655689193717760
[junit4] 2> 4368159 INFO
(coreZkRegister-16663-thread-1-processing-n:127.0.0.1:43980_ x:collection1
c:collection1) [n:127.0.0.1:43980_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more
replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 4368261 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] 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> 4368261 INFO
(zkCallback-30354-thread-1-processing-n:127.0.0.1:43980_) [n:127.0.0.1:43980_
] 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> 4368731 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.SolrTestCaseJ4
Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-3-001/cores/collection1
[junit4] 2> 4368732 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-3-001
of type TLOG
[junit4] 2> 4368735 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 4368737 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@19da04ed{/,null,AVAILABLE}
[junit4] 2> 4368738 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@32d6f878{HTTP/1.1,[http/1.1]}{127.0.0.1:37716}
[junit4] 2> 4368738 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.e.j.s.Server
Started @4372266ms
[junit4] 2> 4368738 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:44131/hdfs__localhost_44131__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-master_checkout_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001_tempDir-002_jetty3,
replicaType=TLOG, solrconfig=solrconfig.xml, hostContext=/, hostPort=37716,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-3-001/cores}
[junit4] 2> 4368739 ERROR
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 4368739 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 4368739 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4368739 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4368739 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T16:45:16.972Z
[junit4] 2> 4368742 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 4368742 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-3-001/solr.xml
[junit4] 2> 4368749 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.SolrXmlConfig
MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9, but no JMX
reporters were configured - adding default JMX reporter.
[junit4] 2> 4368752 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:45162/solr
[junit4] 2> 4368769 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4368771 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 4368773 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37716_
[junit4] 2> 4368775 INFO (zkCallback-30342-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4368775 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4368775 INFO
(zkCallback-30354-thread-1-processing-n:127.0.0.1:43980_) [n:127.0.0.1:43980_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4368775 INFO
(zkCallback-30338-thread-2-processing-n:127.0.0.1:33100_) [n:127.0.0.1:33100_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4368776 INFO
(zkCallback-30360-thread-1-processing-n:127.0.0.1:37716_) [n:127.0.0.1:37716_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4368909 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4368916 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4368917 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4368919 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-3-001/cores
[junit4] 2> 4368919 INFO
(TEST-StressHdfsTest.test-seed#[5FC01C2D33532281]) [n:127.0.0.1:37716_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4368920 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 4368921 INFO
(OverseerStateUpdate-98103480257609732-127.0.0.1:33100_-n_0000000000)
[n:127.0.0.1:33100_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 4369024 INFO
(zkCallback-30360-thread-1-processing-n:127.0.0.1:37716_) [n:127.0.0.1:37716_
] 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> 4369024 INFO
(zkCallback-30348-thread-1-processing-n:127.0.0.1:45981_) [n:127.0.0.1:45981_
] 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> 4369024 INFO
(zkCallback-30354-thread-1-processing-n:127.0.0.1:43980_) [n:127.0.0.1:43980_
] 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> 4369934 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 4369949 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 4370077 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 4370090 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 4370090 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr.core.collection1.shard1.core_node3' (registry
'solr.core.collection1.shard1.core_node3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@58887ce9
[junit4] 2> 4370090 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:44131/solr_hdfs_home
[junit4] 2> 4370090 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Solr Kerberos Authentication disabled
[junit4] 2> 4370090 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 4370090 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.StressHdfsTest_5FC01C2D33532281-001/shard-3-001/cores/collection1],
dataDir=[hdfs://localhost:44131/solr_hdfs_home/collection1/core_node3/data/]
[junit4] 2> 4370092 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node3/data/snapshot_metadata
[junit4] 2> 4370099 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 4370099 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 4370099 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Creating new single instance HDFS BlockCache
[junit4] 2> 4370104 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 4370104 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node3/data
[junit4] 2> 4370122 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:44131/solr_hdfs_home/collection1/core_node3/data/index
[junit4] 2> 4370128 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 4370128 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 4370128 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Creating new single instance HDFS BlockCache
[junit4] 2> 4370132 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 4370132 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=25,
maxMergedSegmentMB=70.68359375, floorSegmentMB=0.595703125,
forceMergeDeletesPctAllowed=9.843885925992309, segmentsPerTier=19.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.32485238773878367
[junit4] 2> 4370140 INFO (IPC Server handler 7 on 44131) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57767 is
added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5a89b1b7-79a7-4e5b-b22a-75f2a0f7a5d4:NORMAL:127.0.0.1:60595|RBW],
ReplicaUC[[DISK]DS-f3a4ed9f-f395-4fba-a1a2-3bbe54989399:NORMAL:127.0.0.1:57767|FINALIZED]]}
size 0
[junit4] 2> 4370140 INFO (IPC Server handler 6 on 44131) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:60595 is
added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-5a89b1b7-79a7-4e5b-b22a-75f2a0f7a5d4:NORMAL:127.0.0.1:60595|RBW],
ReplicaUC[[DISK]DS-f3a4ed9f-f395-4fba-a1a2-3bbe54989399:NORMAL:127.0.0.1:57767|FINALIZED]]}
size 0
[junit4] 2> 4370145 WARN
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 4370192 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 4370192 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4370192 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 4370215 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 4370215 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 4370217 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=668559482,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 4370220 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@2aaf8111[collection1] main]
[junit4] 2> 4370222 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 4370222 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4370223 INFO
(coreLoadExecutor-16679-thread-1-processing-n:127.0.0.1:37716_)
[n:127.0.0.1:37716_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 4370224 INFO
(searcherExecutor-16680-thread-1-processing-n:127.0.0.1:37716_ x:collection1
c:collection1) [n:127.0.0.1:37716_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2aaf8111[collection1]
[...truncated too long message...]
cet_i_dv=PostingsFormat(name=LuceneFixedGap),
text=PostingsFormat(name=MockRandom),
range_facet_l=PostingsFormat(name=LuceneFixedGap),
timestamp=PostingsFormat(name=LuceneFixedGap),
txt_t=PostingsFormat(name=MockRandom)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Memory),
_version_=DocValuesFormat(name=Lucene70),
multiDefault=DocValuesFormat(name=Direct),
intDefault=DocValuesFormat(name=Lucene70),
range_facet_i_dv=DocValuesFormat(name=Lucene70),
id=DocValuesFormat(name=Memory), text=DocValuesFormat(name=Asserting),
intDvoDefault=DocValuesFormat(name=Asserting),
timestamp=DocValuesFormat(name=Lucene70),
range_facet_l=DocValuesFormat(name=Lucene70),
txt_t=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=58,
maxMBSortInHeap=6.238577090193301, sim=RandomSimilarity(queryNorm=true): {},
locale=lv-LV, timezone=Asia/Sakhalin
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=2,free=228200376,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [SpatialHeatmapFacetsTest,
TestRandomDVFaceting, SolrSlf4jReporterTest, FastVectorHighlighterTest,
TestBadConfig, TestLegacyFieldCache, TestTolerantUpdateProcessorRandomCloud,
RequiredFieldsTest, TestQuerySenderListener,
OverriddenZkACLAndCredentialsProvidersTest, SpellCheckCollatorWithCollapseTest,
TestStressLiveNodes, TestPseudoReturnFields, PathHierarchyTokenizerFactoryTest,
PeerSyncReplicationTest, TestUninvertingReader, TestLegacyTerms,
TestTestInjection, CursorPagingTest, TestFieldCache,
CurrencyFieldOpenExchangeTest, StandardRequestHandlerTest, BasicZkTest,
TestCollectionAPIs, TestSubQueryTransformer, DefaultValueUpdateProcessorTest,
HighlighterMaxOffsetTest, ResponseLogComponentTest, InfixSuggestersTest,
HdfsDirectoryTest, TestStressLucene, CreateCollectionCleanupTest,
TestRealTimeGet, SolrMetricManagerTest, TestFilteredDocIdSet, TestSolr4Spatial,
CdcrUpdateLogTest, SpellingQueryConverterTest, TestLazyCores, SmileWriterTest,
AnalysisAfterCoreReloadTest, TestOmitPositions,
TestLMJelinekMercerSimilarityFactory, SubstringBytesRefFilterTest,
QueryElevationComponentTest, NotRequiredUniqueKeyTest, SearchHandlerTest,
ConfigureRecoveryStrategyTest, TestSystemCollAutoCreate, PreAnalyzedFieldTest,
TestSortByMinMaxFunction, TestAddFieldRealTimeGet, TestFastWriter,
TestBackupRepositoryFactory, SolrRequestParserTest, TestExactStatsCache,
TestLFUCache, TestSuggestSpellingConverter,
UniqFieldsUpdateProcessorFactoryTest, OverseerRolesTest,
TestSolrDeletionPolicy2, TestDelegationWithHadoopAuth, TestReplicationHandler,
RegexBoostProcessorTest, ConcurrentDeleteAndCreateCollectionTest,
TestMissingGroups, TestAnalyzedSuggestions, TestBulkSchemaConcurrent,
TestQueryWrapperFilter, HdfsBasicDistributedZk2Test, SolrIndexMetricsTest,
TestSolrConfigHandler, SignatureUpdateProcessorFactoryTest,
ChaosMonkeySafeLeaderWithPullReplicasTest, AtomicUpdateProcessorFactoryTest,
CacheHeaderTest, TestExportWriter, TestCloudInspectUtil,
TestReRankQParserPlugin, DistributedQueryElevationComponentTest,
UninvertDocValuesMergePolicyTest, ReturnFieldsTest, TestRangeQuery,
QueryEqualityTest, DateMathParserTest, TestUseDocValuesAsStored,
HttpPartitionTest, HttpSolrCallGetCoreTest, LeaderFailureAfterFreshStartTest,
BasicAuthStandaloneTest, TestDocumentBuilder, CopyFieldTest, JvmMetricsTest,
TestHighlightDedupGrouping, TestNamedUpdateProcessors, BlockCacheTest,
UpdateLogTest, TestDistributedStatsComponentCardinality,
DistributedSpellCheckComponentTest, ImplicitSnitchTest,
DocExpirationUpdateProcessorFactoryTest, TestFieldResource,
TestFileDictionaryLookup, TestSolrCoreSnapshots, TestJmxIntegration,
CdcrRequestHandlerTest, SolrXmlInZkTest, DeleteShardTest,
PreAnalyzedUpdateProcessorTest, SimpleCollectionCreateDeleteTest,
TestPivotHelperCode, TestBulkSchemaAPI, ReplaceNodeTest, SyncSliceTest,
FileBasedSpellCheckerTest, UUIDFieldTest, TestDFRSimilarityFactory,
TestConfigReload, TestCloudNestedDocsSort, ShardRoutingTest,
TestRandomFaceting, TestZkChroot, ZkCLITest, AutoCommitTest, TestFaceting,
TestHashPartitioner, TestJoin, SolrCoreTest, TestGroupingSearch,
SolrCmdDistributorTest, PeerSyncTest, TestSort, TestFiltering,
TestFunctionQuery, SoftAutoCommitTest, ShowFileRequestHandlerTest,
CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest,
TestCoreDiscovery, SpellCheckCollatorTest, CoreAdminHandlerTest,
TestFoldingMultitermQuery, DocValuesTest, SpatialFilterTest, TestCSVLoader,
PolyFieldTest, SchemaVersionSpecificBehaviorTest,
SolrCoreCheckLockOnStartupTest, TestAtomicUpdateErrorCases, SortByFunctionTest,
TestRemoteStreaming, DocValuesMultiTest, TestSolrDeletionPolicy1,
SolrInfoBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest,
TestSurroundQueryParser, DisMaxRequestHandlerTest, DirectSolrSpellCheckerTest,
TermsComponentTest, DocumentBuilderTest, TermVectorComponentTest,
MoreLikeThisHandlerTest, IndexSchemaRuntimeFieldTest, TestCSVResponseWriter,
TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestMergePolicyConfig,
MultiTermTest, SampleTest, TestDocSet, TestBinaryField, TestSearchPerf,
NumericFieldsTest, MinimalSchemaTest, TestElisionMultitermQuery,
TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties,
SpellPossibilityIteratorTest, TestCodecSupport, ChaosMonkeyNothingIsSafeTest,
CdcrReplicationDistributedZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, CustomCollectionTest,
DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest,
DocValuesNotIndexedTest, LeaderElectionContextKeyTest,
LeaderFailoverAfterPartitionTest, MissingSegmentRecoveryTest,
MultiThreadedOCPTest, RollingRestartTest, TestTolerantUpdateProcessorCloud,
TlogReplayBufferedWhileIndexingTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest,
HdfsSyncSliceTest, HdfsUnloadDistributedZkTest, StressHdfsTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=StressHdfsTest
-Dtests.seed=5FC01C2D33532281 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=lv-LV -Dtests.timezone=Asia/Sakhalin -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | StressHdfsTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 2
object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory]
[junit4] >
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory
[junit4] > at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at
org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:361)
[junit4] > at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3015)
[junit4] > at
org.apache.solr.core.SolrCore.close(SolrCore.java:1579)
[junit4] > at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:994)
[junit4] > at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:855)
[junit4] > at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:979)
[junit4] > at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611)
[junit4] > at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
[junit4] > at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[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)
[junit4] >
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException:
org.apache.lucene.store.NRTCachingDirectory
[junit4] > at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at
org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:361)
[junit4] > at
org.apache.solr.core.SolrCore.cleanupOldIndexDirectories(SolrCore.java:3015)
[junit4] > at
org.apache.solr.core.SolrCore.close(SolrCore.java:1579)
[junit4] > at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:994)
[junit4] > at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:855)
[junit4] > at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:979)
[junit4] > at
org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:611)
[junit4] > at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197)
[junit4] > at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[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)
[junit4] > at
__randomizedtesting.SeedInfo.seed([5FC01C2D33532281]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:295)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [562/725 (1!)] on J0 in 92.24s, 1 test, 1 failure <<<
FAILURES!
[...truncated 43899 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
