Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4298/
Java: 64bit/jdk1.8.0_25 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.ReplicationFactorTest.testDistribSearch
Error Message:
org.apache.http.NoHttpResponseException: The target server failed to respond
Stack Trace:
org.apache.solr.client.solrj.SolrServerException:
org.apache.http.NoHttpResponseException: The target server failed to respond
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:736)
at
org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:277)
at
org.apache.solr.cloud.ReplicationFactorTest.doTest(ReplicationFactorTest.java:123)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor42.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to
respond
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
at
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
at
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
at
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
at
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
at
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
at
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:353)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312)
at
org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:552)
at
org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:549)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.testDistribSearch
Error Message:
Could not get expected value CY val modified for path [response, params, y, c]
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 CY val modified for
path [response, params, y, c] full output {
"responseHeader":{
"status":0,
"QTime":0},
"response":{
"znodeVersion":0,
"params":{"x":{
"a":"A val",
"b":"B val",
"":{"v":0}}}}}
at
__randomizedtesting.SeedInfo.seed([9BBECD098F55746E:1A584311F80A1452]: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:256)
at
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:225)
at
org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:61)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor42.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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 9913 lines...]
[junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerCloud
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\init-core-data-001
[junit4] 2> 1276811 T4583 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 1276811 T4583
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /ce_t/jp
[junit4] 2> 1276818 T4583 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 1276820 T4583 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1276849 T4584 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1276929 T4583 oasc.ZkTestServer.run start zk server on
port:51082
[junit4] 2> 1276930 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1276932 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1276939 T4591 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@54f6f96 name:ZooKeeperConnection
Watcher:127.0.0.1:51082 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1276940 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1276940 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1276940 T4583 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1276951 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1276955 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1276960 T4594 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5cc7551 name:ZooKeeperConnection
Watcher:127.0.0.1:51082/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1276961 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1276961 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1276961 T4583 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1276967 T4583 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1276972 T4583 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1276976 T4583 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1276982 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1276986 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1276995 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 1276996 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1277002 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1277002 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1277010 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1277011 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1277018 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1277018 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1277024 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1277035 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1277053 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1277053 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1277060 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1277060 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1277067 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1277067 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1277072 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1277072 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1277078 T4583 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1277078 T4583 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1279374 T4583 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1279377 T4583 oejs.AbstractConnector.doStart Started
SelectChannelConnector@127.0.0.1:51089
[junit4] 2> 1279380 T4583 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 1279380 T4583 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1279380 T4583 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-002
[junit4] 2> 1279380 T4583 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-002\'
[junit4] 2> 1279416 T4583 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-002\solr.xml
[junit4] 2> 1279434 T4583 oasc.CoreContainer.<init> New CoreContainer
1587418759
[junit4] 2> 1279435 T4583 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-002\]
[junit4] 2> 1279435 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1279436 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1279436 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1279436 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1279437 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1279437 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1279437 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1279438 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1279438 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1279438 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1279439 T4583 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1279439 T4583 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1279440 T4583 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1279440 T4583 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1279441 T4583 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:51082/solr
[junit4] 2> 1279441 T4583 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1279441 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1279443 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1279446 T4606 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2c9235f2
name:ZooKeeperConnection Watcher:127.0.0.1:51082 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1279447 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1279447 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1279451 T4585 oazs.NIOServerCnxn.doIO WARN caught end of
stream exception EndOfStreamException: Unable to read additional data from
client sessionid 0x14acaf178df0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1279455 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1279457 T4609 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6b7fc143
name:ZooKeeperConnection Watcher:127.0.0.1:51082/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1279458 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1279460 T4583 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 1279465 T4583 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 1279471 T4583 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 1279475 T4583 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 1279480 T4583 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 1279486 T4583 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1279488 T4583 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51089_ce_t%2Fjp
[junit4] 2> 1279492 T4583 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:51089_ce_t%2Fjp
[junit4] 2> 1279495 T4583 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 1279497 T4583 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 1279499 T4583 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1279501 T4583 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:51089_ce_t%2Fjp
[junit4] 2> 1279501 T4583 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 1279503 T4583 oasc.Overseer.start Overseer
(id=93109880778326019-127.0.0.1:51089_ce_t%2Fjp-n_0000000000) starting
[junit4] 2> 1279505 T4583 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 1279522 T4583 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1279524 T4583 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 1279525 T4611 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 1279537 T4583 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 1279542 T4583
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1279554 T4610 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 1279571 T4613 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 1279571 T4613 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1279575 T4613 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1279575 T4609 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1279584 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51089/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51089_ce_t%2Fjp",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1279585 T4610 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51089/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51089_ce_t%2Fjp",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1279587 T4610 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 1279587 T4610 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1279590 T4609 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> 1280637 T4613 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1280637 T4613 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 1280640 T4613 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1280640 T4613 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1280644 T4613 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1280644 T4613 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-002\collection1\'
[junit4] 2> 1280644 T4613 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 1280650 T4613 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 1280650 T4613 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 1280694 T4613 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1280700 T4613 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1280713 T4613 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1280731 T4613 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1280748 T4613 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1280752 T4613 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1280760 T4613 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1280927 T4613 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1280937 T4613 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1280939 T4613 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1280973 T4613 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1280979 T4613 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1280980 T4613 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1280980 T4613
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1280980 T4613
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1280980 T4613 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1280987 T4613
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1280987 T4613
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1280987 T4613 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 1280987 T4613 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1280989 T4613 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-002\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/control/data\
[junit4] 2> 1280989 T4613 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@67814daa
[junit4] 2> 1280990 T4613 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/control/data\
[junit4] 2> 1280990 T4613 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/control/data\index/
[junit4] 2> 1280990 T4613 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001\control\data\index' doesn't exist. Creating
new index...
[junit4] 2> 1280991 T4613 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/control/data\index
[junit4] 2> 1280993 T4613 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=17, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=27.6123046875,
floorSegmentMB=0.4228515625, forceMergeDeletesPctAllowed=1.8706964840551665,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1280994 T4613 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@411f9fc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54e67e1a),segFN=segments_1,generation=1}
[junit4] 2> 1280994 T4613 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1281000 T4613 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1281001 T4613 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1281002 T4613 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1281002 T4613 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1281002 T4613 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1281003 T4613 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1281004 T4613 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1281004 T4613 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1281005 T4613 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1281005 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1281006 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1281007 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1281007 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1281008 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1281009 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1281010 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1281010 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1281011 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1281012 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1281013 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1281014 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1281015 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1281015 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1281016 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1281017 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1281017 T4613 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1281018 T4613 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1281029 T4613 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1281032 T4613 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1281034 T4613 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1281036 T4613 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1281037 T4613 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1281041 T4613 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1281041 T4613 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1281042 T4613 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=1.6435546875,
floorSegmentMB=1.30078125, forceMergeDeletesPctAllowed=12.12388002978615,
segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1281042 T4613 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@411f9fc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54e67e1a),segFN=segments_1,generation=1}
[junit4] 2> 1281042 T4613 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1281043 T4613 oass.SolrIndexSearcher.<init> Opening
Searcher@62e6c456[collection1] main
[junit4] 2> 1281043 T4613 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1281046 T4613 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1281046 T4613 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1281047 T4613
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1281047 T4613 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1281047 T4613 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1281059 T4613
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1281059 T4613 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1281059 T4613 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1281059 T4613 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1281060 T4613 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1281062 T4614 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@62e6c456[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1281063 T4617 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:51089/ce_t/jp
collection:control_collection shard:shard1
[junit4] 2> 1281067 T4617 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1281075 T4583 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1281075 T4583 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1281076 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1281078 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1281081 T4617 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1281081 T4620 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3e058760
name:ZooKeeperConnection Watcher:127.0.0.1:51082/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1281082 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1281082 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1281085 T4609 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1281085 T4583
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1281086 T4617
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1281087 T4617 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2242 name=collection1
org.apache.solr.core.SolrCore@4774c37a
url=http://127.0.0.1:51089/ce_t/jp/collection1 node=127.0.0.1:51089_ce_t%2Fjp
C2242_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:51089/ce_t/jp, node_name=127.0.0.1:51089_ce_t%2Fjp,
state=down}
[junit4] 2> 1281087 T4617 C2242 P51089 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:51089/ce_t/jp/collection1/
[junit4] 2> 1281087 T4617 C2242 P51089 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1281087 T4617 C2242 P51089 oasc.SyncStrategy.syncToMe
http://127.0.0.1:51089/ce_t/jp/collection1/ has no replicas
[junit4] 2> 1281088 T4617 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:51089/ce_t/jp/collection1/ shard1
[junit4] 2> 1281088 T4617 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1281089 T4610 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> 1281095 T4583 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 1281097 T4583 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 1281098 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1281099 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1281101 T4609 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1281102 T4623 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7548850 name:ZooKeeperConnection
Watcher:127.0.0.1:51082/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1281103 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1281103 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1281103 T4610 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":"http://127.0.0.1:51089/ce_t/jp";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1281109 T4610 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: 1
[junit4] 2> 1281110 T4610 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 1281136 T4609 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> 1281137 T4620 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> 1281149 T4610 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 1281151 T4620 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> 1281166 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1281211 T4617 oasc.ZkController.register We are
http://127.0.0.1:51089/ce_t/jp/collection1/ and leader is
http://127.0.0.1:51089/ce_t/jp/collection1/
[junit4] 2> 1281212 T4617 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:51089/ce_t/jp
[junit4] 2> 1281212 T4617 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1281212 T4617 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 1281212 T4617 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1281215 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1281219 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51089/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51089_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1281221 T4610 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51089/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51089_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1281338 T4620 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> 1281339 T4624 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> 1283261 T4583 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 1283264 T4583 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1283284 T4583 oejs.AbstractConnector.doStart Started
SelectChannelConnector@127.0.0.1:51104
[junit4] 2> 1283285 T4583 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 1283287 T4583 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1283287 T4583 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-003
[junit4] 2> 1283287 T4583 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-003\'
[junit4] 2> 1283341 T4583 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-003\solr.xml
[junit4] 2> 1283362 T4583 oasc.CoreContainer.<init> New CoreContainer
957695673
[junit4] 2> 1283362 T4583 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-003\]
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1283364 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1283367 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1283367 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1283367 T4583 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1283367 T4583 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1283367 T4583 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1283370 T4583 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1283370 T4583 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:51082/solr
[junit4] 2> 1283370 T4583 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1283370 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1283372 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1283378 T4636 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@30aaad12
name:ZooKeeperConnection Watcher:127.0.0.1:51082 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1283380 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1283380 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1283389 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1283395 T4639 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4fda7e81
name:ZooKeeperConnection Watcher:127.0.0.1:51082/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1283396 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1283409 T4583
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1284485 T4583 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51104_ce_t%2Fjp
[junit4] 2> 1284486 T4583 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:51104_ce_t%2Fjp
[junit4] 2> 1284502 T4583 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1284519 T4640 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1284519 T4640 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1284524 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1284527 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51104/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51104_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1284528 T4640 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1284528 T4640 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1284531 T4610 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51104/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51104_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1284531 T4610 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1284531 T4610 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 1284532 T4640 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1284537 T4610 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1284539 T4640 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 1284539 T4640 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1284542 T4639 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1284543 T4639 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 1285601 T4640 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1285601 T4640 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1285603 T4640 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1285603 T4640 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1285605 T4640 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1285605 T4640 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-003\collection1\'
[junit4] 2> 1285609 T4640 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 1285611 T4640 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 1285612 T4640 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 1285643 T4640 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1285647 T4640 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1285659 T4640 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1285666 T4640 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1285687 T4640 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1285690 T4640 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1285697 T4640 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1285852 T4640 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1285865 T4640 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1285867 T4640 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1285887 T4640 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1285892 T4640 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1285896 T4640 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1285899 T4640
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1285899 T4640
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1285899 T4640 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1285901 T4640
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1285901 T4640
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1285901 T4640 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1285902 T4640 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1285902 T4640 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-003\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/jetty1\
[junit4] 2> 1285902 T4640 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@67814daa
[junit4] 2> 1285903 T4640 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/jetty1\
[junit4] 2> 1285903 T4640 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/jetty1\index/
[junit4] 2> 1285903 T4640 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001\jetty1\index' doesn't exist. Creating new
index...
[junit4] 2> 1285904 T4640 oasc.CachingDirectoryFactory.get return new
directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-001/jetty1\index
[junit4] 2> 1285904 T4640 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=17, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=27.6123046875,
floorSegmentMB=0.4228515625, forceMergeDeletesPctAllowed=1.8706964840551665,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1285905 T4640 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1648e387
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20fa1130),segFN=segments_1,generation=1}
[junit4] 2> 1285905 T4640 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1285908 T4640 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1285909 T4640 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1285909 T4640 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1285910 T4640 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1285910 T4640 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1285910 T4640 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1285911 T4640 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1285911 T4640 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1285911 T4640 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1285912 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1285912 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1285913 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1285913 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1285914 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1285915 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1285918 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1285918 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1285919 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1285922 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1285923 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1285923 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1285924 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1285924 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1285925 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1285925 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1285926 T4640 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1285926 T4640 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1285931 T4640 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1285933 T4640 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1285934 T4640 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1285936 T4640 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1285936 T4640 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1285939 T4640 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1285940 T4640 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1285942 T4640 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=14, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=1.6435546875,
floorSegmentMB=1.30078125, forceMergeDeletesPctAllowed=12.12388002978615,
segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1285943 T4640 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1648e387
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@20fa1130),segFN=segments_1,generation=1}
[junit4] 2> 1285943 T4640 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1285944 T4640 oass.SolrIndexSearcher.<init> Opening
Searcher@3360e852[collection1] main
[junit4] 2> 1285944 T4640 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1285947 T4640 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1285947 T4640 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1285948 T4640
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1285949 T4640 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1285949 T4640 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1285950 T4640
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1285950 T4640 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1285950 T4640 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1285951 T4640 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1285951 T4641 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@3360e852[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1285953 T4640 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1285955 T4644 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:51104/ce_t/jp collection:collection1
shard:shard2
[junit4] 2> 1285956 T4583 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1285956 T4583 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1285959 T4644 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 1285972 T4644 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 1285978 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1285980 T4644
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1285980 T4644 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2243 name=collection1
org.apache.solr.core.SolrCore@262e95d1
url=http://127.0.0.1:51104/ce_t/jp/collection1 node=127.0.0.1:51104_ce_t%2Fjp
C2243_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:51104/ce_t/jp, node_name=127.0.0.1:51104_ce_t%2Fjp,
state=down}
[junit4] 2> 1285981 T4644 C2243 P51104 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:51104/ce_t/jp/collection1/
[junit4] 2> 1285981 T4644 C2243 P51104 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1285981 T4644 C2243 P51104 oasc.SyncStrategy.syncToMe
http://127.0.0.1:51104/ce_t/jp/collection1/ has no replicas
[junit4] 2> 1285981 T4644 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:51104/ce_t/jp/collection1/ shard2
[junit4] 2> 1285982 T4644 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 1285985 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 1285990 T4610 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1285993 T4639 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1285994 T4639 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 1286008 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:51104/ce_t/jp";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1286011 T4610 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 1286013 T4639 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1286015 T4639 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1286023 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1286060 T4644 oasc.ZkController.register We are
http://127.0.0.1:51104/ce_t/jp/collection1/ and leader is
http://127.0.0.1:51104/ce_t/jp/collection1/
[junit4] 2> 1286060 T4644 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:51104/ce_t/jp
[junit4] 2> 1286060 T4644 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1286060 T4644 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1286060 T4644 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1286063 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1286067 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51104/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51104_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1286070 T4610 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51104/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51104_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1286074 T4610 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 1286076 T4639 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1286079 T4644 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1286081 T4639 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1288084 T4583 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 1288085 T4583 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1288090 T4583 oejs.AbstractConnector.doStart Started
SelectChannelConnector@127.0.0.1:51113
[junit4] 2> 1288092 T4583 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 1288092 T4583 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1288092 T4583 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-004
[junit4] 2> 1288092 T4583 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-004\'
[junit4] 2> 1288131 T4583 oasc.ConfigSolr.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-004\solr.xml
[junit4] 2> 1288162 T4583 oasc.CoreContainer.<init> New CoreContainer
838426376
[junit4] 2> 1288162 T4583 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-004\]
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1288164 T4583 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1288164 T4583 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1288166 T4583 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1288167 T4583 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1288167 T4583 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1288167 T4583 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:51082/solr
[junit4] 2> 1288167 T4583 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1288167 T4583
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1288172 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1288175 T4656 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23a74142
name:ZooKeeperConnection Watcher:127.0.0.1:51082 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1288177 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1288179 T4583 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1288186 T4583 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1288190 T4659 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3409b33e
name:ZooKeeperConnection Watcher:127.0.0.1:51082/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1288191 T4583 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1288210 T4583
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1289390 T4583 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51113_ce_t%2Fjp
[junit4] 2> 1289392 T4583 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:51113_ce_t%2Fjp
[junit4] 2> 1289404 T4583 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1289419 T4660 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1289419 T4660 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1289422 T4624 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1289424 T4660 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1289424 T4660 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1289425 T4610 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51113/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51113_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1289425 T4660 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1289427 T4610 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:51113/ce_t/jp";,
[junit4] 2> "node_name":"127.0.0.1:51113_ce_t%2Fjp",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1289427 T4610 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1289427 T4610 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1289429 T4660 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1289429 T4660 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1289430 T4610 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 1289431 T4659 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1289431 T4639 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1289432 T4639 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1289433 T4659 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1290597 T4660 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1290597 T4660 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1290600 T4660 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1290600 T4660 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1290603 T4660 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1290604 T4660 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud
9BBECD098F55746E-001\tempDir-004\collection1\'
[junit4] 2> 1290609 T4660 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 1290612 T4660 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 1290614 T4660 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%209BBECD098F55746E-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 1290664 T4660 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1290670 T4660 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1290689 T4660 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1290697 T4660 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.0.0
[junit4] 2> 1290725 T4660 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1290731 T4660 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1290741 T4660 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1290961 T4660 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1290974 T4660 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1290978 T4660 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1291003 T4660 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1291009 T4660 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1291015 T4660 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1291019 T4660
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1291020 T4660
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1291020 T4660 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1291022 T4660
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1291023 T4660
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1291023 T4660 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collec
[...truncated too long message...]
sponseParser.parseHead(DefaultHttpResponseParser.java:143)
[junit4] > at
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260)
[junit4] > at
org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283)
[junit4] > at
org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251)
[junit4] > at
org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197)
[junit4] > at
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271)
[junit4] > at
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123)
[junit4] > at
org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682)
[junit4] > at
org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486)
[junit4] > at
org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106)
[junit4] > at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:353)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:552)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:549)
[junit4] > at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > ... 1 moreThrowable #2:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=5853, name=updateExecutor-837-thread-1,
state=RUNNABLE, group=TGRP-ReplicationFactorTest]
[junit4] > Caused by: java.util.concurrent.RejectedExecutionException:
Task
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner@7ac13b3e
rejected from java.util.concurrent.ThreadPoolExecutor@35dff2e9[Shutting down,
pool size = 1, active threads = 1, queued tasks = 0, completed tasks = 26]
[junit4] > at
__randomizedtesting.SeedInfo.seed([9BBECD098F55746E]:0)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] > at
org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:278)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1618031 T5684 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest
9BBECD098F55746E-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50),
sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=ar_SD,
timezone=EST5EDT
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_25
(64-bit)/cpus=2,threads=1,free=72909568,total=287698944
[junit4] 2> NOTE: All tests run in this JVM: [PingRequestHandlerTest,
TestManagedStopFilterFactory, TestDistributedGrouping, DistanceUnitsTest,
TestRawResponseWriter, ZkStateWriterTest, CurrencyFieldXmlFileTest,
SpellCheckComponentTest, CachingDirectoryFactoryTest, TestInitParams,
TestPhraseSuggestions, QueryElevationComponentTest, TestCodecSupport,
BlockDirectoryTest, HighlighterTest, TestLFUCache, TermsComponentTest,
CoreAdminHandlerTest, UpdateParamsTest, TestChildDocTransformer,
DocumentBuilderTest, TestOverriddenPrefixQueryForCustomFieldType,
DirectUpdateHandlerOptimizeTest, TestManagedSchemaDynamicFieldResource,
DebugComponentTest, PathHierarchyTokenizerFactoryTest, DeleteReplicaTest,
TestSolr4Spatial, TestStressVersions, NoCacheHeaderTest, SuggestComponentTest,
TestMaxScoreQueryParser, UnloadDistributedZkTest, TestDistributedSearch,
TestBlobHandler, TestFiltering, TestSolrXmlPersistence, DocValuesMissingTest,
DisMaxRequestHandlerTest, TestFieldResource, TestTrie, TestAnalyzedSuggestions,
BadComponentTest, TestManagedSchemaFieldResource, LeaderElectionTest,
TestManagedSchemaFieldTypeResource, TestSuggestSpellingConverter,
TestBulkSchemaConcurrent, AnalysisErrorHandlingTest, QueryResultKeyTest,
DistribDocExpirationUpdateProcessorTest, ZkNodePropsTest,
TestBlendedInfixSuggestions, SolrXmlInZkTest, CircularListTest,
DeleteShardTest, TestUniqueKeyFieldResource, SpatialFilterTest,
MinimalSchemaTest, TestUtils, DistributedQueryElevationComponentTest,
OverseerRolesTest, IndexSchemaRuntimeFieldTest,
SharedFSAutoReplicaFailoverTest, TestCoreDiscovery, SimplePostToolTest,
CacheHeaderTest, ResponseHeaderTest, HdfsRecoveryZkTest,
SignatureUpdateProcessorFactoryTest, TestRecovery, InfoHandlerTest,
TestIndexSearcher, JSONWriterTest, TestCustomSort, TestInfoStreamLogging,
TestRemoteStreaming, CoreAdminRequestStatusTest, TestShortCircuitedRequests,
TestSolrXmlPersistor, TestGroupingSearch, TestWriterPerf,
ExitableDirectoryReaderTest, DocumentAnalysisRequestHandlerTest,
CoreContainerCoreInitFailuresTest, TestCoreContainer, HdfsLockFactoryTest,
UUIDUpdateProcessorFallbackTest, TermVectorComponentTest, TestBadConfig,
TestZkChroot, TestDynamicFieldResource, TestCloudManagedSchema,
TestReloadAndDeleteDocs, SOLR749Test, SimpleFacetsTest, ResourceLoaderTest,
SuggesterTest, TestDynamicLoading, DistributedIntervalFacetingTest,
DistributedFacetPivotSmallTest, TestWordDelimiterFilterFactory,
MultiThreadedOCPTest, TestSolrQueryParserResource, TestSolrCoreProperties,
TestSchemaResource, TestSimpleQParserPlugin,
StatelessScriptUpdateProcessorFactoryTest, SuggesterFSTTest,
TestMergePolicyConfig, OverriddenZkACLAndCredentialsProvidersTest,
TestExtendedDismaxParser, TestHighFrequencyDictionaryFactory,
TestManagedResource, OverseerStatusTest, SynonymTokenizerTest,
TestSolr4Spatial2, TestJmxIntegration, TestFieldTypeCollectionResource,
TestDocumentBuilder, DocExpirationUpdateProcessorFactoryTest,
LukeRequestHandlerTest, SortByFunctionTest, AnalyticsQueryTest,
TestSolrConfigHandlerCloud, TestDefaultSimilarityFactory,
TestComplexPhraseQParserPlugin, TestNoOpRegenerator, TestReRankQParserPlugin,
TestRestManager, TestShardHandlerFactory, StressHdfsTest, TestHashPartitioner,
CSVRequestHandlerTest, MultiTermTest, AnalysisAfterCoreReloadTest,
TestClusterStateMutator, TestRecoveryHdfs, DocValuesMultiTest, TestCSVLoader,
HdfsUnloadDistributedZkTest, DistributedFacetPivotWhiteBoxTest,
TestCollectionAPI, SharedFSAutoReplicaFailoverUtilsTest, AutoCommitTest,
TestOrdValues, PrimUtilsTest, AddSchemaFieldsUpdateProcessorFactoryTest,
TestStressRecovery, TestFastWriter, TestStressLucene, DirectUpdateHandlerTest,
RegexBoostProcessorTest, TestMiniSolrCloudCluster, TestBM25SimilarityFactory,
LeaderElectionIntegrationTest, BJQParserTest,
TestReversedWildcardFilterFactory, TestSearcherReuse, DistanceFunctionTest,
TestSort, TestFastOutputStream, RAMDirectoryFactoryTest, TestSolrXml,
SolrCoreTest, ReplicationFactorTest]
[junit4] Completed in 84.55s, 1 test, 1 error <<< FAILURES!
[...truncated 900 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:529: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:477: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:190: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:508:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1363:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:970:
There were test failures: 459 suites, 1867 tests, 1 error, 1 failure, 56
ignored (31 assumptions)
Total time: 129 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_25
-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
