Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11418/
Java: 32bit/jdk1.7.0_72 -server -XX:+UseParallelGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.DistribCursorPagingTest
Error Message:
Some resources were not closed, shutdown, or released.
Stack Trace:
java.lang.AssertionError: Some resources were not closed, shutdown, or released.
at __randomizedtesting.SeedInfo.seed([5BF726B50F116C88]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:189)
at sun.reflect.GeneratedMethodAccessor28.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
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:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.testDistribSearch
Error Message:
Could not get expected value P val for path [response, params, y, p] full
output { "responseHeader":{ "status":0, "QTime":0}, "response":{
"znodeVersion":0, "params":{"x":{ "a":"A val", "b":"B
val", "":{"v":0}}}}}
Stack Trace:
java.lang.AssertionError: Could not get expected value P val for path
[response, params, y, p] full output {
"responseHeader":{
"status":0,
"QTime":0},
"response":{
"znodeVersion":0,
"params":{"x":{
"a":"A val",
"b":"B val",
"":{"v":0}}}}}
at
__randomizedtesting.SeedInfo.seed([5BF726B50F116C88:DA11A8AD784E0CB4]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:259)
at
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:261)
at
org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:70)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor39.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
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:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.util.SimplePostToolTest.testTypeSupported
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([5BF726B50F116C88:CF431056182ECDE7]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.util.SimplePostToolTest.testTypeSupported(SimplePostToolTest.java:116)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
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:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9322 lines...]
[junit4] Suite: org.apache.solr.cloud.DistribCursorPagingTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/init-core-data-001
[junit4] 2> 458119 T2708 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (false)
[junit4] 2> 458119 T2708 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 458122 T2708 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 458123 T2708 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 458123 T2709 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 458223 T2708 oasc.ZkTestServer.run start zk server on
port:38958
[junit4] 2> 458224 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 458225 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 458228 T2716 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@582c36 name:ZooKeeperConnection
Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 458229 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 458230 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 458230 T2708 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 458236 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 458237 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 458238 T2719 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1b315fd name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 458239 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 458240 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 458240 T2708 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 458244 T2708 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 458248 T2708 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 458252 T2708 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 458256 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-deeppaging.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 458256 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 458262 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-sorts.xml
to /configs/conf1/schema.xml
[junit4] 2> 458263 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 458267 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 458267 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 458271 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 458272 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 458275 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 458276 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 458280 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 458280 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 458284 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 458285 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 458289 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 458289 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 458293 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 458294 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 458298 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 458298 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 458302 T2708 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 458303 T2708 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 458309 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 458310 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 458312 T2722 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5cb584 name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 458312 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 458313 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 458551 T2708 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 458683 T2708 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 458686 T2708 oejs.AbstractConnector.doStart Started
[email protected]:33115
[junit4] 2> 458688 T2708 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ed5d6
[junit4] 2> 458689 T2708 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 458689 T2708 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-002
[junit4] 2> 458689 T2708 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-002/'
[junit4] 2> 458699 T2708 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-002/solr.xml
[junit4] 2> 458734 T2708 oasc.CoreContainer.<init> New CoreContainer
4468161
[junit4] 2> 458735 T2708 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-002/]
[junit4] 2> 458735 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 458735 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 458736 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 458737 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 458739 T2708 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 458740 T2708 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 458741 T2708 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 458741 T2708 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 458741 T2708 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:38958/solr
[junit4] 2> 458741 T2708 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 458742 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 458742 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 458744 T2734 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@31c0ba name:ZooKeeperConnection
Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 458744 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 458745 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 458747 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 458748 T2737 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23bca7 name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 458748 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 458750 T2708 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 458755 T2708 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 458758 T2708 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 458762 T2708 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 458765 T2708 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 458770 T2708 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 458772 T2708 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33115_
[junit4] 2> 458773 T2708 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:33115_
[junit4] 2> 458777 T2708 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 458779 T2708 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 458782 T2708 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 458784 T2708 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:33115_
[junit4] 2> 458785 T2708 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 458788 T2708 oasc.Overseer.start Overseer
(id=93129743307636740-127.0.0.1:33115_-n_0000000000) starting
[junit4] 2> 458791 T2708 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 458804 T2708 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 458805 T2739 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 458806 T2708 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 458809 T2708 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 458811 T2708
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 458817 T2738 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 458821 T2741 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 458821 T2741 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 458822 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 458822 T2741 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 458827 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:33115_",
[junit4] 2> "base_url":"https://127.0.0.1:33115";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null} current state version: 0
[junit4] 2> 458829 T2738 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:33115_",
[junit4] 2> "base_url":"https://127.0.0.1:33115";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null}
[junit4] 2> 458829 T2738 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 458829 T2738 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 458831 T2737 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 459823 T2741 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 459824 T2741 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 459825 T2741 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 459825 T2741 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 459826 T2741 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 459827 T2741 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-002/collection1/'
[junit4] 2> 459828 T2741 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 459828 T2741 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 459829 T2741 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 459849 T2741 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 459851 T2741 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 459857 T2741 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 459867 T2741 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 459896 T2741 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 459898 T2741 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 459902 T2741 oass.IndexSchema.readSchema [collection1] Schema
name=sortable
[junit4] 2> 460300 T2741 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 460301 T2741 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 460301 T2741 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 460301 T2741 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/control/data/
[junit4] 2> 460301 T2741 oasc.SolrCore.<init> JMX monitoring not detected
for core: collection1
[junit4] 2> 460302 T2741 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/control/data
[junit4] 2> 460302 T2741 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/control/data/index/
[junit4] 2> 460302 T2741 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 460303 T2741 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/control/data/index
[junit4] 2> 460303 T2741 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5160384261868395]
[junit4] 2> 460304 T2741 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@12882d6
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a5c4b6),segFN=segments_1,generation=1}
[junit4] 2> 460304 T2741 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 460304 T2741 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 460305 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 460306 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 460307 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 460308 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 460308 T2741 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 460310 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 460311 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 460312 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 460313 T2741 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 460314 T2741 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 460315 T2741 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 460315 T2741 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 460316 T2741 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=41, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=98.9736328125,
floorSegmentMB=1.4140625, forceMergeDeletesPctAllowed=2.6684977050046887,
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8196520713965341
[junit4] 2> 460316 T2741 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@12882d6
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a5c4b6),segFN=segments_1,generation=1}
[junit4] 2> 460316 T2741 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 460317 T2741 oass.SolrIndexSearcher.<init> Opening
Searcher@1671c4c[collection1] main
[junit4] 2> 460317 T2741 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 460318 T2741 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 460318 T2741 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 460319 T2741
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 460319 T2741 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 460319 T2741 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 460320 T2741
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 460321 T2741 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 460321 T2741 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 460321 T2741 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 460321 T2742 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1671c4c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 460321 T2741 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 460322 T2745 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:33115 collection:control_collection
shard:shard1
[junit4] 2> 460323 T2708 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 460324 T2708 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 460324 T2745 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 460328 T2745 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 460328 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 460328 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 460328 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 460329 T2745
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 460329 T2745 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1670 name=collection1
org.apache.solr.core.SolrCore@1752fec url=https://127.0.0.1:33115/collection1
node=127.0.0.1:33115_ C1670_STATE=coll:control_collection core:collection1
props:{core=collection1, node_name=127.0.0.1:33115_,
base_url=https://127.0.0.1:33115, state=down}
[junit4] 2> 460329 T2745 C1670 P33115 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:33115/collection1/
[junit4] 2> 460329 T2745 C1670 P33115 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 460329 T2748 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@c33920 name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 460329 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 460329 T2745 C1670 P33115 oasc.SyncStrategy.syncToMe
https://127.0.0.1:33115/collection1/ has no replicas
[junit4] 2> 460330 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 460330 T2745 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:33115/collection1/ shard1
[junit4] 2> 460330 T2745 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 460330 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 460331 T2708
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 460332 T2708 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 460333 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 460333 T2708 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 460333 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 460333 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:33115";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 460334 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 460334 T2737 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 460334 T2751 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@b69ca8 name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 460334 T2748 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 460335 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 460336 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 460338 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 2
[junit4] 2> 460338 T2738 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 460339 T2752 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 460339 T2738 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 460340 T2748 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 460383 T2745 oasc.ZkController.register We are
https://127.0.0.1:33115/collection1/ and leader is
https://127.0.0.1:33115/collection1/
[junit4] 2> 460383 T2745 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:33115
[junit4] 2> 460383 T2745 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 460383 T2745 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 460384 T2745 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 460385 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 460386 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:33115_",
[junit4] 2> "base_url":"https://127.0.0.1:33115";,
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null} current state version: 3
[junit4] 2> 460387 T2738 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:33115_",
[junit4] 2> "base_url":"https://127.0.0.1:33115";,
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null}
[junit4] 2> 460490 T2748 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 460490 T2737 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 460552 T2708 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 460552 T2708 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 460556 T2708 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 460558 T2708 oejs.AbstractConnector.doStart Started
[email protected]:36453
[junit4] 2> 460560 T2708 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ed5d6
[junit4] 2> 460560 T2708 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 460560 T2708 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-003
[junit4] 2> 460561 T2708 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-003/'
[junit4] 2> 460571 T2708 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-003/solr.xml
[junit4] 2> 460592 T2708 oasc.CoreContainer.<init> New CoreContainer
4569133
[junit4] 2> 460592 T2708 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-003/]
[junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 460593 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 460594 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 460595 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 460595 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 460596 T2708 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 460598 T2708 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 460598 T2708 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 460598 T2708 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 460599 T2708 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:38958/solr
[junit4] 2> 460599 T2708 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 460599 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 460600 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 460601 T2764 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1d360eb name:ZooKeeperConnection
Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 460601 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 460602 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 460604 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 460605 T2767 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@9d9660 name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 460605 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 460610 T2708
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 461613 T2708 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36453_
[junit4] 2> 461615 T2708 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:36453_
[junit4] 2> 461622 T2708 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 461631 T2768 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 461631 T2768 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 461633 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 461634 T2768 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 461635 T2768 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 461635 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:36453_",
[junit4] 2> "base_url":"https://127.0.0.1:36453";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null} current state version: 4
[junit4] 2> 461636 T2768 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 461637 T2738 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:36453_",
[junit4] 2> "base_url":"https://127.0.0.1:36453";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null}
[junit4] 2> 461637 T2738 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 461637 T2768 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 461637 T2738 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 461638 T2768 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 461743 T2738 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 461744 T2767 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 461745 T2767 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 462638 T2768 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 462639 T2768 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 462640 T2768 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 462640 T2768 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 462642 T2768 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 462642 T2768 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-003/collection1/'
[junit4] 2> 462643 T2768 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 462643 T2768 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 462644 T2768 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 462660 T2768 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 462663 T2768 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 462669 T2768 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 462680 T2768 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 462700 T2768 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 462702 T2768 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 462706 T2768 oass.IndexSchema.readSchema [collection1] Schema
name=sortable
[junit4] 2> 462861 T2768 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 462862 T2768 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 462862 T2768 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 462862 T2768 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty1/
[junit4] 2> 462862 T2768 oasc.SolrCore.<init> JMX monitoring not detected
for core: collection1
[junit4] 2> 462863 T2768 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty1
[junit4] 2> 462863 T2768 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty1/index/
[junit4] 2> 462863 T2768 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 462864 T2768 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty1/index
[junit4] 2> 462864 T2768 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5160384261868395]
[junit4] 2> 462865 T2768 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@196cc8d
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14f44f9),segFN=segments_1,generation=1}
[junit4] 2> 462865 T2768 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 462865 T2768 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 462866 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 462867 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 462868 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 462869 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 462869 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 462869 T2768 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 462872 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 462873 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 462874 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 462875 T2768 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 462876 T2768 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 462877 T2768 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 462877 T2768 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 462877 T2768 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=41, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=98.9736328125,
floorSegmentMB=1.4140625, forceMergeDeletesPctAllowed=2.6684977050046887,
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8196520713965341
[junit4] 2> 462878 T2768 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@196cc8d
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14f44f9),segFN=segments_1,generation=1}
[junit4] 2> 462878 T2768 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 462878 T2768 oass.SolrIndexSearcher.<init> Opening
Searcher@1954cf[collection1] main
[junit4] 2> 462878 T2768 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 462880 T2768 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 462880 T2768 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 462881 T2768
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 462881 T2768 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 462881 T2768 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 462882 T2768
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 462882 T2768 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 462882 T2768 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 462883 T2768 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 462883 T2769 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1954cf[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 462883 T2768 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 462884 T2772 oasc.ZkController.register Register replica -
core:collection1 address:https://127.0.0.1:36453 collection:collection1
shard:shard1
[junit4] 2> 462885 T2708 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 462885 T2708 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 462886 T2772 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 462893 T2772 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 462894 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 462895 T2772
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 462895 T2772 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1671 name=collection1
org.apache.solr.core.SolrCore@8075a8 url=https://127.0.0.1:36453/collection1
node=127.0.0.1:36453_ C1671_STATE=coll:collection1 core:collection1
props:{core=collection1, node_name=127.0.0.1:36453_,
base_url=https://127.0.0.1:36453, state=down}
[junit4] 2> 462895 T2772 C1671 P36453 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:36453/collection1/
[junit4] 2> 462896 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 462896 T2772 C1671 P36453 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 462896 T2772 C1671 P36453 oasc.SyncStrategy.syncToMe
https://127.0.0.1:36453/collection1/ has no replicas
[junit4] 2> 462897 T2772 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:36453/collection1/ shard1
[junit4] 2> 462897 T2772 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 462897 T2738 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 462898 T2767 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 462899 T2767 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 462901 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:36453";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 462902 T2738 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 462902 T2767 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 462903 T2767 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 462904 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 462951 T2772 oasc.ZkController.register We are
https://127.0.0.1:36453/collection1/ and leader is
https://127.0.0.1:36453/collection1/
[junit4] 2> 462951 T2772 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=https://127.0.0.1:36453
[junit4] 2> 462951 T2772 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 462951 T2772 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 462951 T2772 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 462952 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 462953 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:36453_",
[junit4] 2> "base_url":"https://127.0.0.1:36453";,
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null} current state version: 4
[junit4] 2> 462954 T2738 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "node_name":"127.0.0.1:36453_",
[junit4] 2> "base_url":"https://127.0.0.1:36453";,
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null}
[junit4] 2> 462955 T2738 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 462955 T2772 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 462955 T2767 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 462956 T2767 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 463108 T2708 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 463108 T2708 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 463112 T2708 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 463114 T2708 oejs.AbstractConnector.doStart Started
[email protected]:60319
[junit4] 2> 463116 T2708 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@9ed5d6
[junit4] 2> 463116 T2708 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 463117 T2708 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-004
[junit4] 2> 463117 T2708 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-004/'
[junit4] 2> 463127 T2708 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-004/solr.xml
[junit4] 2> 463146 T2708 oasc.CoreContainer.<init> New CoreContainer
24846494
[junit4] 2> 463147 T2708 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-004/]
[junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 463148 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 463149 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 463150 T2708 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 463151 T2708 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 463153 T2708 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 463153 T2708 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 463153 T2708 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 463153 T2708 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:38958/solr
[junit4] 2> 463154 T2708 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 463154 T2708
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 463155 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 463156 T2784 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@fc4a9a name:ZooKeeperConnection
Watcher:127.0.0.1:38958 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 463157 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 463157 T2708 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 463159 T2708 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 463160 T2787 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4fa3f6 name:ZooKeeperConnection
Watcher:127.0.0.1:38958/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 463160 T2708 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 463167 T2708
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 464172 T2708 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60319_
[junit4] 2> 464173 T2708 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:60319_
[junit4] 2> 464176 T2708 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 464181 T2788 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 464181 T2788 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 464182 T2737 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 464183 T2788 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 464183 T2788 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 464183 T2738 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:60319_",
[junit4] 2> "base_url":"https://127.0.0.1:60319";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null} current state version: 4
[junit4] 2> 464183 T2788 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 464183 T2738 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "node_name":"127.0.0.1:60319_",
[junit4] 2> "base_url":"https://127.0.0.1:60319";,
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "roles":null}
[junit4] 2> 464184 T2738 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 464184 T2738 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 464184 T2788 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 464184 T2788 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 464184 T2738 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 464185 T2767 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 464185 T2787 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 464185 T2767 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 464185 T2787 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 465184 T2788 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 465185 T2788 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 465185 T2788 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 465185 T2788 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 465187 T2788 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 465187 T2788 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-004/collection1/'
[junit4] 2> 465187 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 465188 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 465188 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest%205BF726B50F116C88-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 465198 T2788 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 465200 T2788 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 465205 T2788 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 465221 T2788 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 465242 T2788 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 465244 T2788 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 465249 T2788 oass.IndexSchema.readSchema [collection1] Schema
name=sortable
[junit4] 2> 465387 T2788 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 465388 T2788 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 465388 T2788 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 465388 T2788 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty2/
[junit4] 2> 465388 T2788 oasc.SolrCore.<init> JMX monitoring not detected
for core: collection1
[junit4] 2> 465389 T2788 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty2
[junit4] 2> 465389 T2788 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty2/index/
[junit4] 2> 465390 T2788 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 465390 T2788 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DistribCursorPagingTest
5BF726B50F116C88-001/tempDir-001/jetty2/index
[junit4] 2> 465391 T2788 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=48,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5160384261868395]
[junit4] 2> 465391 T2788 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@86bca7
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cbb0a1),segFN=segments_1,generation=1}
[junit4] 2> 465391 T2788 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 465392 T2788 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 465392 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 465393 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 465394 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 465395 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 465396 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 465396 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 465396 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 465398 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 465399 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 465400 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 465401 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 465402 T2788 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 465403 T2788 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 465403 T2788 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 465404 T2788 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=41, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=98.9736328125,
floorSegmentMB=1.4140625, forceMergeDeletesPctAllowed=2.6684977050046887,
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8196520713965341
[junit4] 2> 465404 T2788 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@86bca7
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cbb0a1),segFN=segments_1,generation=1}
[junit4] 2> 465404 T2788 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 465404 T2788 oass.SolrIndexSearcher.<init> Opening
Searcher@7dcd05[collection1] main
[junit4] 2> 465405 T2788 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 465406 T2788 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 465406 T2788 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 465407 T2788
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 465407 T2788 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 465407 T2788 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 465408 T2788
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 465408 T2788 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 465409
[...truncated too long message...]
01/tempDir-001/jetty4/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
5BF726B50F116C88-001/tempDir-001/jetty4/index;done=false>>]
[junit4] 2> 1833161 T10671 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
5BF726B50F116C88-001/tempDir-001/jetty4/index
[junit4] 2> 1833163 T10671 oasc.Overseer.close Overseer
(id=93129830915899406-127.0.0.1:42295_-n_0000000004) closing
[junit4] 2> 1833163 T10893 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:42295_
[junit4] 2> 1834663 T10885 oascc.ZkStateReader$7.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1834664 T10886 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1834686 T10671 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1834875 T10671 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 1834876 T10671 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:42970 42970
[junit4] 2> 1835582 T10672 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:42970 42970
[junit4] 2> 1835584 T10672 oasc.ZkTestServer$ZKServerMain.runFromConfig
WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 50 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=testDistribSearch
-Dtests.seed=5BF726B50F116C88 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=sr_ME -Dtests.timezone=Asia/Yekaterinburg -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 40.7s J0 | TestSolrConfigHandlerCloud.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected
value P val for path [response, params, y, p] full output {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "response":{
[junit4] > "znodeVersion":0,
[junit4] > "params":{"x":{
[junit4] > "a":"A val",
[junit4] > "b":"B val",
[junit4] > "":{"v":0}}}}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([5BF726B50F116C88:DA11A8AD784E0CB4]:0)
[junit4] > at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:259)
[junit4] > at
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:261)
[junit4] > at
org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:70)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1835591 T10671 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
5BF726B50F116C88-001
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=DefaultSimilarity, locale=sr_ME, timezone=Asia/Yekaterinburg
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.7.0_72
(32-bit)/cpus=8,threads=1,free=156880232,total=440401920
[junit4] 2> NOTE: All tests run in this JVM:
[DefaultValueUpdateProcessorTest, ShardRoutingTest, TestExtendedDismaxParser,
DistanceFunctionTest, SimpleFacetsTest, DateMathParserTest,
UnloadDistributedZkTest, TestReplicationHandlerBackup, CoreAdminHandlerTest,
PathHierarchyTokenizerFactoryTest, TestLMDirichletSimilarityFactory,
TestStressRecovery, NoCacheHeaderTest, TestRequestStatusCollectionAPI,
TestAnalyzeInfixSuggestions, DistributedQueryElevationComponentTest,
AddBlockUpdateTest, CachingDirectoryFactoryTest, ResourceLoaderTest,
CoreAdminCreateDiscoverTest, DistributedQueueTest, SolrPluginUtilsTest,
TestBlendedInfixSuggestions, TestDefaultStatsCache, SuggestComponentTest,
TestCollationField, BasicDistributedZkTest, TestShardHandlerFactory,
SolrInfoMBeanTest, SolrIndexConfigTest, DistributedFacetPivotSmallAdvancedTest,
TestSolrCoreProperties, TestExceedMaxTermLength,
TestSerializedLuceneMatchVersion, HdfsSyncSliceTest,
SolrCoreCheckLockOnStartupTest, DeleteReplicaTest, TestReplicaProperties,
XmlUpdateRequestHandlerTest, FacetPivotSmallTest, UUIDFieldTest,
TestDynamicLoading, OpenExchangeRatesOrgProviderTest, TestCloudSchemaless,
HdfsWriteToMultipleCollectionsTest, PreAnalyzedFieldTest,
HdfsCollectionsAPIDistributedZkTest, DistribCursorPagingTest,
CoreMergeIndexesAdminHandlerTest, AlternateDirectoryTest, TestFunctionQuery,
TestSolr4Spatial2, TestRawResponseWriter, TestWordDelimiterFilterFactory,
CoreContainerCoreInitFailuresTest, EnumFieldTest, HdfsLockFactoryTest,
SolrCoreTest, FieldAnalysisRequestHandlerTest, TestSchemaManager,
MinimalSchemaTest, DistributedFacetPivotLongTailTest, TestChildDocTransformer,
TestFieldCollectionResource, TestSolrXmlPersistor, ZkControllerTest,
SystemInfoHandlerTest, TestGroupingSearch, TestNRTOpen,
TestCursorMarkWithoutUniqueKey, TestCoreContainer, TestCSVLoader,
HardAutoCommitTest, AnalysisAfterCoreReloadTest, TestUpdate,
TestOverriddenPrefixQueryForCustomFieldType, TestStressLucene,
TestUniqueKeyFieldResource, TestReloadAndDeleteDocs, SuggesterWFSTTest,
TestFastWriter, TriLevelCompositeIdRoutingTest, HdfsBasicDistributedZkTest,
TestSolrDynamicMBean, TestFieldTypeResource, TestDocBasedVersionConstraints,
TestSweetSpotSimilarityFactory, TestIndexSearcher, SuggesterFSTTest,
TestSchemaResource, TestBinaryResponseWriter,
DistribDocExpirationUpdateProcessorTest, TestComplexPhraseQParserPlugin,
TestBlobHandler, TestManagedResourceStorage, TestExactStatsCache,
RequestHandlersTest, TestExactSharedStatsCache, PrimUtilsTest,
TestJmxIntegration, HdfsChaosMonkeySafeLeaderTest,
TestCloudManagedSchemaConcurrent, TestQueryTypes, BadCopyFieldTest,
TestStressReorder, SimpleMLTQParserTest, TestRandomDVFaceting,
NumericFieldsTest, DeleteInactiveReplicaTest, TestComponentsName,
OutOfBoxZkACLAndCredentialsProvidersTest, TestManagedResource, RecoveryZkTest,
ClusterStateUpdateTest, LeaderElectionTest, TestFaceting, TestHashPartitioner,
TestStressVersions, PeerSyncTest, ConvertedLegacyTest, TestFiltering,
ShowFileRequestHandlerTest, SolrIndexSplitterTest, SuggesterTest,
SpatialFilterTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases,
TestRemoteStreaming, CacheHeaderTest, LukeRequestHandlerTest,
StandardRequestHandlerTest, DirectSolrSpellCheckerTest,
DocumentAnalysisRequestHandlerTest, TestOmitPositions, TestValueSourceCache,
TestIndexingPerformance, TestArbitraryIndexDir, IndexSchemaTest,
UpdateRequestProcessorFactoryTest, JSONWriterTest, TestAnalyzedSuggestions,
BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter,
CSVRequestHandlerTest, HighlighterConfigTest, TestQuerySenderListener,
TestQuerySenderNoQuery, CopyFieldTest, TestSolrDeletionPolicy2, SampleTest,
TestBinaryField, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions,
TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestPhraseSuggestions,
SynonymTokenizerTest, TestDFRSimilarityFactory, TestIBSimilarityFactory,
TestBM25SimilarityFactory, LegacyHTMLStripCharFilterTest, ScriptEngineTest,
PluginInfoTest, SpellingQueryConverterTest, DOMUtilTest, ClusterStateTest,
TestLRUCache, TestUtils, TestDocumentBuilder, SliceStateTest, FileUtilsTest,
TestRTGBase, SolrTestCaseJ4Test, TestDistributedMissingSort,
TestHighlightDedupGrouping, AliasIntegrationTest, ConnectionManagerTest,
CustomCollectionTest, ExternalCollectionsTest,
LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest,
MultiThreadedOCPTest, OverseerStatusTest, RollingRestartTest, SSLMigrationTest,
ShardSplitTest, TestSolrConfigHandlerCloud]
[junit4] Completed on J0 in 40.71s, 1 test, 1 failure <<< FAILURES!
[...truncated 152 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970:
There were test failures: 460 suites, 1871 tests, 1 suite-level error, 2
failures, 47 ignored (22 assumptions)
Total time: 64 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.7.0_72 -server
-XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
