Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11192/
Java: 64bit/jdk1.8.0_40-ea-b04 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
REGRESSION:
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.testDistribSearch
Error Message:
no exception matching expected: 400: Request took too long during query
expansion. Terminating request.
Stack Trace:
java.lang.AssertionError: no exception matching expected: 400: Request took too
long during query expansion. Terminating request.
at
__randomizedtesting.SeedInfo.seed([F507E3F206B0EBC1:74E16DEA71EF8BFD]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertFail(CloudExitableDirectoryReaderTest.java:101)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:75)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTest(CloudExitableDirectoryReaderTest.java:54)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java: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:43)
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)
REGRESSION:
org.apache.solr.cloud.DeleteLastCustomShardedReplicaTest.testDistribSearch
Error Message:
No live SolrServers available to handle this
request:[http://127.0.0.1:53014/_pb/jm, http://127.0.0.1:48923/_pb/jm,
http://127.0.0.1:41267/_pb/jm]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available
to handle this request:[http://127.0.0.1:53014/_pb/jm,
http://127.0.0.1:48923/_pb/jm, http://127.0.0.1:41267/_pb/jm]
at
__randomizedtesting.SeedInfo.seed([F507E3F206B0EBC1:74E16DEA71EF8BFD]:0)
at
org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:322)
at
org.apache.solr.client.solrj.impl.CloudSolrServer.sendRequest(CloudSolrServer.java:880)
at
org.apache.solr.client.solrj.impl.CloudSolrServer.requestWithRetryOnStaleState(CloudSolrServer.java:658)
at
org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:601)
at
org.apache.solr.cloud.DeleteLastCustomShardedReplicaTest.removeAndWaitForLastReplicaGone(DeleteLastCustomShardedReplicaTest.java:117)
at
org.apache.solr.cloud.DeleteLastCustomShardedReplicaTest.doTest(DeleteLastCustomShardedReplicaTest.java:107)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java: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:43)
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.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Could not
remove replica : customcollreplicadeletion/a/core_node1
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:568)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
at
org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:343)
at
org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:304)
... 46 more
Build Log:
[...truncated 11493 lines...]
[junit4] Suite: org.apache.solr.cloud.CloudExitableDirectoryReaderTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/init-core-data-001
[junit4] 2> 972997 T2717 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (true)
[junit4] 2> 972997 T2717 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 972999 T2717 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 973000 T2717 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 973000 T2718 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 973100 T2717 oasc.ZkTestServer.run start zk server on
port:37745
[junit4] 2> 973101 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 973102 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 973113 T2724 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@634b3ad1
name:ZooKeeperConnection Watcher:127.0.0.1:37745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 973113 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 973114 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 973114 T2717 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 973118 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 973118 T2719 oazs.NIOServerCnxn.doIO WARN caught end of stream
exception EndOfStreamException: Unable to read additional data from client
sessionid 0x148bdd2e8d60000, 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> 973144 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 973150 T2726 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5726d323
name:ZooKeeperConnection Watcher:127.0.0.1:37745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 973150 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 973150 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 973151 T2717 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 973153 T2717 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 973154 T2717 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 973155 T2717 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 973157 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 973157 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 973159 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 973159 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 973161 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 973161 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 973162 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 973163 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 973164 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 973165 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 973166 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 973166 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 973167 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 973168 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 973169 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 973169 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 973170 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 973171 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 973172 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 973172 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 973174 T2717 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 973174 T2717 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 973435 T2717 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 973439 T2717 oejs.AbstractConnector.doStart Started
[email protected]:37141
[junit4] 2> 973440 T2717 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 973440 T2717 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 973441 T2717 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002
[junit4] 2> 973441 T2717 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/'
[junit4] 2> 973458 T2717 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/solr.xml
[junit4] 2> 973476 T2717 oasc.CoreContainer.<init> New CoreContainer
59886740
[junit4] 2> 973476 T2717 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/]
[junit4] 2> 973477 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 973477 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 973478 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 973478 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 973478 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 973479 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 973479 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 973479 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 973480 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 973480 T2717 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 973481 T2717 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 973481 T2717 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 973481 T2717 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 973482 T2717 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:37745/solr
[junit4] 2> 973482 T2717 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 973482 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 973483 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 973486 T2737 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3f12b57a
name:ZooKeeperConnection Watcher:127.0.0.1:37745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 973486 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 973487 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 973489 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 973492 T2739 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3774a9e0
name:ZooKeeperConnection Watcher:127.0.0.1:37745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 973492 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 973495 T2717 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 973496 T2717 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 973498 T2717 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 973500 T2717 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 973506 T2717 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 973508 T2717 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 973509 T2717 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37141_
[junit4] 2> 973510 T2717 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:37141_
[junit4] 2> 973511 T2717 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 973512 T2717 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 973513 T2717 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 973514 T2717 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:37141_
[junit4] 2> 973514 T2717 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 973516 T2717 oasc.Overseer.start Overseer
(id=92532505908215811-127.0.0.1:37141_-n_0000000000) starting
[junit4] 2> 973517 T2717 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 973522 T2717 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 973522 T2741 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 973523 T2717 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 973533 T2717 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 973535 T2717
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 973537 T2740 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 973538 T2743 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 973538 T2743 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 973540 T2743 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 973540 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 973542 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:37141";,
[junit4] 2> "node_name":"127.0.0.1:37141_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 973542 T2740
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
control_collection with shards [shard1]
[junit4] 2> 973543 T2740
oasc.Overseer$ClusterStateUpdater.createCollection state version
control_collection 1
[junit4] 2> 973543 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 973545 T2744 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> 974540 T2743 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 974541 T2743 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 974542 T2743 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 974542 T2743 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 974544 T2743 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 974544 T2743 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/collection1/'
[junit4] 2> 974545 T2743 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 974545 T2743 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 974546 T2743 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 974564 T2743 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 974576 T2743 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 974577 T2743 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 974582 T2743 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 974671 T2743 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 974674 T2743 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 974675 T2743 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 974681 T2743 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 974683 T2743 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 974691 T2743 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 974692 T2743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 974692 T2743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 974692 T2743 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 974693 T2743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 974693 T2743
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 974693 T2743 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 974693 T2743 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 974693 T2743 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/control/data/
[junit4] 2> 974694 T2743 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f2a19
[junit4] 2> 974694 T2743 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/control/data
[junit4] 2> 974694 T2743 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/control/data/index/
[junit4] 2> 974694 T2743 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/control/data/index'
doesn't exist. Creating new index...
[junit4] 2> 974694 T2743 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/control/data/index
[junit4] 2> 974695 T2743 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=95.365234375,
floorSegmentMB=1.689453125, forceMergeDeletesPctAllowed=28.108563029805378,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5513695255304706
[junit4] 2> 974695 T2743 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11bdf63f)),segFN=segments_1,generation=1}
[junit4] 2> 974695 T2743 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 974697 T2743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 974697 T2743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 974697 T2743 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 974697 T2743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 974698 T2743 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 974698 T2743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 974698 T2743 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 974698 T2743 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 974698 T2743 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 974698 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 974699 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 974699 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 974699 T2743 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 974699 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 974699 T2743 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 974700 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 974700 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 974700 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 974700 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 974700 T2743 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 974702 T2743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974702 T2743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974703 T2743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974703 T2743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 974705 T2743 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 974705 T2743 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 974705 T2743 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=12.369140625,
floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=27.032818367433048,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5259090253667654
[junit4] 2> 974706 T2743 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-11bdf63f)),segFN=segments_1,generation=1}
[junit4] 2> 974706 T2743 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 974706 T2743 oass.SolrIndexSearcher.<init> Opening
Searcher@34a5504e[collection1] main
[junit4] 2> 974706 T2743 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 974713 T2743 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 974713 T2743 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 974713 T2743
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 974714 T2743 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 974714 T2743 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 974714 T2743
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 974714 T2743 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 974715 T2743 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 974719 T2743
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 974719 T2743 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 974719 T2743 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 974721 T2745 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@34a5504e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 974722 T2743 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 974723 T2748 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:37141 collection:control_collection
shard:shard1
[junit4] 2> 974723 T2717 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 974723 T2717 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 974724 T2748 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 974724 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 974725 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 974727 T2750 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7f0170cd
name:ZooKeeperConnection Watcher:127.0.0.1:37745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 974728 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 974728 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 974730 T2717
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 974732 T2717 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 974732 T2748 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 974732 T2717 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 974732 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 974733 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 974735 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 974735 T2752 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6d28ed64
name:ZooKeeperConnection Watcher:127.0.0.1:37745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 974736 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 974735 T2748
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 974736 T2748 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1856 name=collection1
org.apache.solr.core.SolrCore@7e4dc670 url=http://127.0.0.1:37141/collection1
node=127.0.0.1:37141_ C1856_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:37141,
node_name=127.0.0.1:37141_, state=down}
[junit4] 2> 974736 T2748 C1856 P37141 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:37141/collection1/
[junit4] 2> 974737 T2748 C1856 P37141 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 974737 T2748 C1856 P37141 oasc.SyncStrategy.syncToMe
http://127.0.0.1:37141/collection1/ has no replicas
[junit4] 2> 974737 T2748 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:37141/collection1/ shard1
[junit4] 2> 974737 T2748 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 974736 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 974741 T2740 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: collection1
[junit4] 2> 974741 T2740
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 974742 T2740
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 974743 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 974743 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 974744 T2744 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> 974745 T2753 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> 974750 T2753 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> 974750 T2754 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 974794 T2748 oasc.ZkController.register We are
http://127.0.0.1:37141/collection1/ and leader is
http://127.0.0.1:37141/collection1/
[junit4] 2> 974794 T2748 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:37141
[junit4] 2> 974794 T2748 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 974794 T2748 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 974795 T2748 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 974796 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 974801 T2740 oasc.Overseer$ClusterStateUpdater.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:37141";,
[junit4] 2> "node_name":"127.0.0.1:37141_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 974910 T2753 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> 974910 T2744 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> 975031 T2717 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 975032 T2717 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 975038 T2717 oejs.AbstractConnector.doStart Started
[email protected]:55020
[junit4] 2> 975039 T2717 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 975039 T2717 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 975039 T2717 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003
[junit4] 2> 975039 T2717 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/'
[junit4] 2> 975057 T2717 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/solr.xml
[junit4] 2> 975076 T2717 oasc.CoreContainer.<init> New CoreContainer
1272314215
[junit4] 2> 975076 T2717 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/]
[junit4] 2> 975077 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 975077 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 975078 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 975078 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 975078 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 975079 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 975079 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 975080 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 975080 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 975081 T2717 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 975081 T2717 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 975081 T2717 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 975082 T2717 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 975082 T2717 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:37745/solr
[junit4] 2> 975082 T2717 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 975083 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 975083 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 975085 T2765 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1c971d85
name:ZooKeeperConnection Watcher:127.0.0.1:37745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 975085 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 975086 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 975088 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 975089 T2767 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@33346e3b
name:ZooKeeperConnection Watcher:127.0.0.1:37745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 975089 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 975093 T2717
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 976096 T2717 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55020_
[junit4] 2> 976097 T2717 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:55020_
[junit4] 2> 976102 T2717 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 976108 T2769 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 976108 T2769 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 976110 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 976110 T2769 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 976111 T2769 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 976111 T2769 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 976113 T2769 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 976113 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55020";,
[junit4] 2> "node_name":"127.0.0.1:55020_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 976113 T2769 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 976115 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 976115 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 976119 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 976119 T2768 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 976120 T2768 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 977114 T2769 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 977114 T2769 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 977114 T2769 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 977115 T2769 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 977115 T2769 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 977115 T2769 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/collection1/'
[junit4] 2> 977116 T2769 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 977116 T2769 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 977117 T2769 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 977133 T2769 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 977146 T2769 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 977147 T2769 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 977156 T2769 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 977236 T2769 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 977239 T2769 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 977240 T2769 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 977247 T2769 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 977248 T2769 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 977250 T2769 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 977251 T2769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 977251 T2769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 977251 T2769 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 977251 T2769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 977252 T2769
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 977252 T2769 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 977252 T2769 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 977252 T2769 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty1/
[junit4] 2> 977252 T2769 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f2a19
[junit4] 2> 977253 T2769 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty1
[junit4] 2> 977253 T2769 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty1/index/
[junit4] 2> 977253 T2769 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty1/index'
doesn't exist. Creating new index...
[junit4] 2> 977254 T2769 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty1/index
[junit4] 2> 977254 T2769 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=95.365234375,
floorSegmentMB=1.689453125, forceMergeDeletesPctAllowed=28.108563029805378,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5513695255304706
[junit4] 2> 977254 T2769 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-225490c6)),segFN=segments_1,generation=1}
[junit4] 2> 977254 T2769 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 977257 T2769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 977257 T2769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 977257 T2769 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 977257 T2769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 977257 T2769 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 977258 T2769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 977258 T2769 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 977258 T2769 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 977258 T2769 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 977258 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 977259 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 977259 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 977259 T2769 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 977260 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 977260 T2769 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 977260 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 977260 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 977261 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 977261 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 977261 T2769 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 977263 T2769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 977263 T2769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 977264 T2769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 977264 T2769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 977265 T2769 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 977266 T2769 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 977266 T2769 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=12.369140625,
floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=27.032818367433048,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5259090253667654
[junit4] 2> 977266 T2769 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-225490c6)),segFN=segments_1,generation=1}
[junit4] 2> 977267 T2769 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 977267 T2769 oass.SolrIndexSearcher.<init> Opening
Searcher@31231a7e[collection1] main
[junit4] 2> 977267 T2769 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 977268 T2769 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 977268 T2769 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 977268 T2769
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 977268 T2769 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 977268 T2769 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 977269 T2769
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 977269 T2769 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 977269 T2769 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 977269 T2769 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 977271 T2770 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@31231a7e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 977273 T2769 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 977281 T2773 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:55020 collection:collection1
shard:shard2
[junit4] 2> 977281 T2717 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 977282 T2773 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 977282 T2717 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 977285 T2773 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 977285 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 977286 T2773
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 977286 T2773 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1857 name=collection1
org.apache.solr.core.SolrCore@3346c9f7 url=http://127.0.0.1:55020/collection1
node=127.0.0.1:55020_ C1857_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:55020,
node_name=127.0.0.1:55020_, state=down}
[junit4] 2> 977286 T2773 C1857 P55020 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:55020/collection1/
[junit4] 2> 977286 T2773 C1857 P55020 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 977286 T2773 C1857 P55020 oasc.SyncStrategy.syncToMe
http://127.0.0.1:55020/collection1/ has no replicas
[junit4] 2> 977286 T2773 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:55020/collection1/ shard2
[junit4] 2> 977287 T2773 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 977288 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 977289 T2768 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 977289 T2768 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 977291 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 977292 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 977292 T2768 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 977292 T2768 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 977339 T2773 oasc.ZkController.register We are
http://127.0.0.1:55020/collection1/ and leader is
http://127.0.0.1:55020/collection1/
[junit4] 2> 977339 T2773 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:55020
[junit4] 2> 977339 T2773 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 977339 T2773 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 977340 T2773 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 977341 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 977342 T2773 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 977342 T2740 oasc.Overseer$ClusterStateUpdater.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:55020";,
[junit4] 2> "node_name":"127.0.0.1:55020_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 977345 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 977345 T2768 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 977346 T2768 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 977508 T2717 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 977509 T2717 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 977510 T2717 oejs.AbstractConnector.doStart Started
[email protected]:49365
[junit4] 2> 977511 T2717 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 977511 T2717 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 977511 T2717 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004
[junit4] 2> 977512 T2717 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/'
[junit4] 2> 977521 T2717 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/solr.xml
[junit4] 2> 977535 T2717 oasc.CoreContainer.<init> New CoreContainer
624200080
[junit4] 2> 977535 T2717 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/]
[junit4] 2> 977536 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 977537 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 977537 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 977538 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 977538 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 977539 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 977539 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 977540 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 977540 T2717 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 977541 T2717 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 977541 T2717 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 977542 T2717 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 977542 T2717 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 977542 T2717 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:37745/solr
[junit4] 2> 977543 T2717 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 977543 T2717
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 977544 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 977546 T2784 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4afe168a
name:ZooKeeperConnection Watcher:127.0.0.1:37745 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 977546 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 977547 T2717 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 977550 T2717 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 977552 T2786 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4a11fd08
name:ZooKeeperConnection Watcher:127.0.0.1:37745/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 977552 T2717 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 977560 T2717
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 978566 T2717 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49365_
[junit4] 2> 978567 T2717 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:49365_
[junit4] 2> 978569 T2717 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 978573 T2788 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 978573 T2788 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 978574 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 978574 T2788 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 978575 T2788 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 978575 T2788 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 978575 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:49365";,
[junit4] 2> "node_name":"127.0.0.1:49365_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 978576 T2788 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 978576 T2788 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 978577 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Collection already exists with numShards=2
[junit4] 2> 978577 T2740 oasc.Overseer$ClusterStateUpdater.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 978579 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 978579 T2787 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 978579 T2768 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 978580 T2787 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 978580 T2768 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 979576 T2788 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 979576 T2788 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 979577 T2788 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 979578 T2788 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 979579 T2788 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 979579 T2788 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/collection1/'
[junit4] 2> 979580 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 979581 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 979581 T2788 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 979609 T2788 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 979617 T2788 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 979622 T2788 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 979627 T2788 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 979705 T2788 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 979709 T2788 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 979710 T2788 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 979723 T2788 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 979725 T2788 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 979727 T2788 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 979729 T2788
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 979729 T2788
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 979729 T2788 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 979730 T2788
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 979731 T2788
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 979731 T2788 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 979731 T2788 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 979731 T2788 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty2/
[junit4] 2> 979732 T2788 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1c5f2a19
[junit4] 2> 979732 T2788 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty2
[junit4] 2> 979732 T2788 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty2/index/
[junit4] 2> 979732 T2788 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty2/index'
doesn't exist. Creating new index...
[junit4] 2> 979733 T2788 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CloudExitableDirectoryReaderTest-F507E3F206B0EBC1-001/tempDir-001/jetty2/index
[junit4] 2> 979733 T2788 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=11, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=95.365234375,
floorSegmentMB=1.689453125, forceMergeDeletesPctAllowed=28.108563029805378,
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5513695255304706
[junit4] 2> 979734 T2788 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-37e96bae)),segFN=segments_1,generation=1}
[junit4] 2> 979734 T2788 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 979736 T2788 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 979737 T2788 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 979737 T2788 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 979737 T2788 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 979737 T2788 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 979737 T2788 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 979738 T2788 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 979738 T2788 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 979738 T2788 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 979739 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979739 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979739 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979740 T2788 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 979740 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 979740 T2788 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 979740 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 979741 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 979741 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 979741 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 979742 T2788 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 979743 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979744 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979745 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979745 T2788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979747 T2788 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 979747 T2788 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 979748 T2788 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=12.369140625,
floorSegmentMB=0.3251953125, forceMergeDeletesPctAllowed=27.032818367433048,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5259090253667654
[junit4] 2> 979748 T2788 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-37e96bae)),segFN=segments_1,generation=1}
[junit4] 2> 979748 T2788 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 979749 T2788 oass.SolrIndexSearcher.<init> Opening
Searcher@5f936974[collection1] main
[junit4] 2> 979749 T2788 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 979750 T2788 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 979750 T2788 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 979751 T2788
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 979751 T2788 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 979752 T2788 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 979753 T2788
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes
from znode /configs/conf1/_rest_managed.json
[junit4] 2> 979753 T2788 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 979753 T2788 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 979754 T2788 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 979756 T2789 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5f936974[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 979759 T2788 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 979760 T2792 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:49365 collection:collection1
shard:shard1
[junit4] 2> 979760 T2717 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 979760 T2792 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 979760 T2717 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 979767 T2792 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 979768 T2744 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 979768 T2792
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 979768 T2792 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1858 name=collection1
org.apache.solr.core.SolrCore@320c89b url=http://127.0.0.1:49365/collection1
node=127.0.0.1:49365_ C1858_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:49365,
node_name=127.0.0.1:49365_, state=down}
[junit4] 2> 979768 T2792 C1858 P49365 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:49365/collection1/
[junit4] 2> 979769 T2792 C1858 P49365 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 979769 T2792 C1858 P49365 oasc.SyncStrategy.syncToMe
http://127.0.0.1:49365/collection1/ has no replicas
[junit4] 2> 979769 T2792 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:49365/collection1/ shard1
[junit4] 2> 979769 T2792 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 979771 T2740 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/collection1/state.json
[junit4] 2> 979771 T2787 oascc.ZkStateReader$7.
[...truncated too long message...]
he.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 2120483 T6116 oaz.ClientCnxn$EventThread.processEvent ERROR
Error while calling watcher java.util.concurrent.RejectedExecutionException:
Task java.util.concurrent.FutureTask@6ea9ffd6 rejected from
java.util.concurrent.ThreadPoolExecutor@e860099[Terminated, pool size = 0,
active threads = 0, queued tasks = 0, completed tasks = 4]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] 2> at
java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:263)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 2120483 T6116 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@398331a9
name:ZooKeeperConnection Watcher:127.0.0.1:44026/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4] 2> 2120483 T6116 oascc.ConnectionManager.process
Client->ZooKeeper status change trigger but we are already closed
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=DeleteLastCustomShardedReplicaTest -Dtests.method=testDistribSearch
-Dtests.seed=F507E3F206B0EBC1 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=ko_KR -Dtests.timezone=America/Indiana/Vevay
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 127s J0 |
DeleteLastCustomShardedReplicaTest.testDistribSearch <<<
[junit4] > Throwable #1:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available
to handle this request:[http://127.0.0.1:53014/_pb/jm,
http://127.0.0.1:48923/_pb/jm, http://127.0.0.1:41267/_pb/jm]
[junit4] > at
__randomizedtesting.SeedInfo.seed([F507E3F206B0EBC1:74E16DEA71EF8BFD]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:322)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrServer.sendRequest(CloudSolrServer.java:880)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrServer.requestWithRetryOnStaleState(CloudSolrServer.java:658)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:601)
[junit4] > at
org.apache.solr.cloud.DeleteLastCustomShardedReplicaTest.removeAndWaitForLastReplicaGone(DeleteLastCustomShardedReplicaTest.java:117)
[junit4] > at
org.apache.solr.cloud.DeleteLastCustomShardedReplicaTest.doTest(DeleteLastCustomShardedReplicaTest.java:107)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Could not
remove replica : customcollreplicadeletion/a/core_node1
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:568)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:215)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:211)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:343)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:304)
[junit4] > ... 46 more
[junit4] 2> 2120504 T6052 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.DeleteLastCustomShardedReplicaTest-F507E3F206B0EBC1-001
[junit4] 2> 127130 T6051 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {},
docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {},
locale=ko_KR, timezone=America/Indiana/Vevay
[junit4] 2> NOTE: Linux 3.13.0-36-generic amd64/Oracle Corporation
1.8.0_40-ea (64-bit)/cpus=8,threads=1,free=238380120,total=394436608
[junit4] 2> NOTE: All tests run in this JVM: [DocValuesMultiTest,
PluginInfoTest, ShowFileRequestHandlerTest,
DistribDocExpirationUpdateProcessorTest, HighlighterConfigTest,
TestFieldTypeCollectionResource, MergeStrategyTest, TestDFRSimilarityFactory,
MBeansHandlerTest, WordBreakSolrSpellCheckerTest, TestHashPartitioner,
SignatureUpdateProcessorFactoryTest, TestRTGBase, DeleteShardTest,
ResponseLogComponentTest, CoreAdminCreateDiscoverTest, TestCoreDiscovery,
SpellCheckComponentTest, TestRequestStatusCollectionAPI,
TestSolrDeletionPolicy1, TestSolr4Spatial, DocumentAnalysisRequestHandlerTest,
SolrCoreCheckLockOnStartupTest, TestDistribDocBasedVersion,
SolrCmdDistributorTest, DateRangeFieldTest, TestManagedResourceStorage,
TestHighlightDedupGrouping, ZkCLITest, TestChildDocTransformer,
TestDistributedSearch, BJQParserTest, TestFieldCollectionResource,
SuggestComponentTest, EnumFieldTest, TestBinaryResponseWriter,
TestSolrIndexConfig, FastVectorHighlighterTest, TestConfig,
TestSuggestSpellingConverter, SuggesterTest, IndexSchemaTest,
TestFastOutputStream, TestSolrDeletionPolicy2, DirectSolrSpellCheckerTest,
TestInitParams, SimpleFacetsTest, SolrXmlInZkTest, UpdateParamsTest,
MultiThreadedOCPTest, NotRequiredUniqueKeyTest, TestCloudSchemaless,
QueryResultKeyTest, HighlighterTest, TestUniqueKeyFieldResource,
TestJmxIntegration, TestFaceting, TestStandardQParsers,
TestManagedSchemaFieldTypeResource, TestDocSet, TestRemoteStreaming,
TestSolr4Spatial2, ParsingFieldUpdateProcessorsTest,
TestHighFrequencyDictionaryFactory, TestImplicitCoreProperties, TestRangeQuery,
OpenExchangeRatesOrgProviderTest, TestRecoveryHdfs,
CollectionsAPIAsyncDistributedZkTest, TestStressVersions, TestLRUCache,
TestLuceneMatchVersion, CursorPagingTest, TestLMDirichletSimilarityFactory,
TestStressRecovery, BlockDirectoryTest, CoreAdminHandlerTest,
TestReplicationHandlerBackup, SuggesterWFSTTest,
DistributedExpandComponentTest, TestAnalyzedSuggestions, DOMUtilTest,
TestSortingResponseWriter, ExternalFileFieldSortTest,
StandardRequestHandlerTest, TestPivotHelperCode, SpellPossibilityIteratorTest,
TestDocumentBuilder, TestPartialUpdateDeduplication, TestSearchPerf,
TestSchemaNameResource, PrimitiveFieldTypeTest, TestCloudManagedSchema,
UnloadDistributedZkTest, TestCollectionAPI,
DistributedQueryComponentOptimizationTest, MinimalSchemaTest,
TestFoldingMultitermQuery, RequiredFieldsTest, ChaosMonkeyNothingIsSafeTest,
TestStressReorder, BasicDistributedZkTest, SyncSliceTest, OverseerTest,
ShardRoutingTest, TestReplicationHandler, TestMultiCoreConfBootstrap,
TestReload, TestCoreContainer, TestGroupingSearch, BadIndexSchemaTest,
ConvertedLegacyTest, TestSort, TestFunctionQuery, BasicFunctionalityTest,
DirectUpdateHandlerTest, OverseerCollectionProcessorTest,
CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, SimplePostToolTest,
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterTSTTest, TestTrie,
NoCacheHeaderTest, DistanceFunctionTest, SolrInfoMBeanTest, DebugComponentTest,
IndexBasedSpellCheckerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest,
TestQueryUtils, TestWriterPerf, TermsComponentTest, DocumentBuilderTest,
TestValueSourceCache, TestIndexingPerformance, FieldAnalysisRequestHandlerTest,
IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, ReturnFieldsTest,
TestCSVResponseWriter, JsonLoaderTest, CSVRequestHandlerTest,
CoreContainerCoreInitFailuresTest, SOLR749Test, TestQuerySenderNoQuery,
TestMergePolicyConfig, SampleTest, NumericFieldsTest,
TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties,
TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory,
TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest,
URLClassifyProcessorTest, PreAnalyzedFieldTest, TestSolrJ, ZkNodePropsTest,
FileUtilsTest, DistributedIntervalFacetingTest, AssignTest,
AsyncMigrateRouteKeyTest, DeleteLastCustomShardedReplicaTest]
[junit4] Completed on J0 in 127.91s, 1 test, 1 error <<< FAILURES!
[...truncated 284 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:524: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:472: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:189: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:496: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1360:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:962:
There were test failures: 431 suites, 1778 tests, 1 error, 1 failure, 45
ignored (23 assumptions)
Total time: 92 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_40-ea-b04
-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: [email protected]
For additional commands, e-mail: [email protected]
