Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11440/
Java: 32bit/jdk1.9.0-ea-b44 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDistribSearch
Error Message:
Could not successfully add blob after 150 attempts. Expecting 2 items. time
elapsed 15,740 output for url is { "responseHeader":{ "status":0,
"QTime":1}, "response":{ "numFound":1, "start":0, "docs":[{
"id":"test/1", "md5":"e7832b5fcc813969d3da5310cdf8445d",
"blobName":"test", "version":1,
"timestamp":"2015-01-14T13:24:07.147Z", "size":5296}]}}
Stack Trace:
java.lang.AssertionError: Could not successfully add blob after 150 attempts.
Expecting 2 items. time elapsed 15,740 output for url is {
"responseHeader":{
"status":0,
"QTime":1},
"response":{
"numFound":1,
"start":0,
"docs":[{
"id":"test/1",
"md5":"e7832b5fcc813969d3da5310cdf8445d",
"blobName":"test",
"version":1,
"timestamp":"2015-01-14T13:24:07.147Z",
"size":5296}]}}
at
__randomizedtesting.SeedInfo.seed([61A64DEB9096C407:E040C3F3E7C9A43B]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.handler.TestBlobHandler.postAndCheck(TestBlobHandler.java:150)
at
org.apache.solr.core.TestDynamicLoading.dynamicLoading(TestDynamicLoading.java:114)
at
org.apache.solr.core.TestDynamicLoading.doTest(TestDynamicLoading.java:70)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.GeneratedMethodAccessor36.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10309 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/init-core-data-001
[junit4] 2> 1730446 T9578 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 1730447 T9578
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system
property: /uo_r/g
[junit4] 2> 1730449 T9578 oas.SolrTestCaseJ4.setUp ###Starting testDown
[junit4] 2> 1730450 T9578 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1730450 T9579 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1730550 T9578 oasc.ZkTestServer.run start zk server on
port:56917
[junit4] 2> 1730551 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1730552 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1730554 T9586 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@16d7c42 name:ZooKeeperConnection
Watcher:127.0.0.1:56917 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1730554 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1730555 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1730555 T9578 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1730558 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1730559 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1730560 T9589 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@348201 name:ZooKeeperConnection
Watcher:127.0.0.1:56917/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1730561 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1730561 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1730562 T9578 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1730565 T9578 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1730568 T9578 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1730570 T9578 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1730571 T9578 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> 1730572 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1730575 T9578 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> 1730576 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1730577 T9578 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> 1730578 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1730580 T9578 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> 1730580 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1730582 T9578 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> 1730582 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1730584 T9578 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> 1730584 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1730586 T9578 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> 1730586 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1730588 T9578 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> 1730588 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1730590 T9578 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> 1730590 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1730592 T9578 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> 1730593 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1730594 T9578 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> 1730595 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1730597 T9578 oas.SolrTestCaseJ4.tearDown ###Ending testDown
[junit4] 2> 1730598 T9578 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:56917 56917
[junit4] 2> 1731143 T9579 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:56917 56917
[junit4] 2> 1731145 T9578 oas.SolrTestCaseJ4.tearDown ###Ending testDown
[junit4] 2> 1731146 T9578 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:56917 56917
[junit4] 2> 1731147 T9578 oas.SolrTestCaseJ4.tearDown ###Ending testDown
[junit4] 2> 1731147 T9578 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:56917 56917
[junit4] 2> 1731153 T9578 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 1731154 T9578 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1731155 T9590 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 1731254 T9578 oasc.ZkTestServer.run start zk server on
port:59691
[junit4] 2> 1731255 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1731256 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1731258 T9597 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@23f358 name:ZooKeeperConnection
Watcher:127.0.0.1:59691 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1731258 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1731259 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1731260 T9578 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1731268 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1731269 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1731270 T9600 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3a6164 name:ZooKeeperConnection
Watcher:127.0.0.1:59691/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1731270 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1731271 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1731271 T9578 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 1731273 T9578 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 1731274 T9578 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 1731276 T9578 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 1731277 T9578 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> 1731278 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 1731280 T9578 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> 1731280 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 1731282 T9578 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> 1731283 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1731284 T9578 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> 1731285 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 1731286 T9578 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> 1731287 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 1731288 T9578 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> 1731289 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 1731290 T9578 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> 1731291 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 1731292 T9578 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> 1731293 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 1731294 T9578 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> 1731294 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1731296 T9578 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> 1731296 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 1731298 T9578 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> 1731298 T9578 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 1731581 T9578 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1731584 T9578 oejs.AbstractConnector.doStart Started
[email protected]:35104
[junit4] 2> 1731585 T9578 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@d25a40
[junit4] 2> 1731585 T9578 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1731586 T9578 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-003
[junit4] 2> 1731586 T9578 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-003/'
[junit4] 2> 1731604 T9578 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-003/solr.xml
[junit4] 2> 1731638 T9578 oasc.CoreContainer.<init> New CoreContainer
11585785
[junit4] 2> 1731639 T9578 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-003/]
[junit4] 2> 1731639 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1731640 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1731640 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1731640 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1731641 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1731641 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1731641 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1731642 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1731642 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1731642 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1731643 T9578 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1731644 T9578 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1731644 T9578 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1731644 T9578 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1731645 T9578 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59691/solr
[junit4] 2> 1731645 T9578 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1731645 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1731646 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1731648 T9612 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1934323 name:ZooKeeperConnection
Watcher:127.0.0.1:59691 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1731648 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1731649 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1731651 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1731652 T9615 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@a468ea name:ZooKeeperConnection
Watcher:127.0.0.1:59691/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1731652 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1731654 T9578 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 1731656 T9578 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 1731658 T9578 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 1731660 T9578 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 1731662 T9578 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 1731664 T9578 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1731665 T9578 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35104_uo_r%2Fg
[junit4] 2> 1731666 T9578 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:35104_uo_r%2Fg
[junit4] 2> 1731668 T9578 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 1731669 T9578 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 1731670 T9578 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1731671 T9578 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:35104_uo_r%2Fg
[junit4] 2> 1731672 T9578 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 1731673 T9578 oasc.Overseer.start Overseer
(id=93142504595390467-127.0.0.1:35104_uo_r%2Fg-n_0000000000) starting
[junit4] 2> 1731675 T9578 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 1731680 T9578 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1731681 T9617 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 1731682 T9616 oasc.Overseer$ClusterStateUpdater.run Starting
to work on the main queue
[junit4] 2> 1731683 T9578 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 1731685 T9578 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 1731686 T9578
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1731689 T9619 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 1731690 T9619 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1731690 T9615 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1731691 T9619 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1731694 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:35104/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:35104_uo_r%2Fg",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1731694 T9616 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:35104/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:35104_uo_r%2Fg",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1731694 T9616 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 1731695 T9616 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1731696 T9615 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> 1732691 T9619 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1732692 T9619 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 1732692 T9619 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1732693 T9619 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1732694 T9619 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1732694 T9619 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-003/collection1/'
[junit4] 2> 1732695 T9619 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 1732695 T9619 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 1732696 T9619 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 1732723 T9619 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1732725 T9619 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1732733 T9619 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1732741 T9619 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 1732758 T9619 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1732760 T9619 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1732772 T9619 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1732938 T9619 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1732943 T9619 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1732945 T9619 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1732957 T9619 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1732961 T9619 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1732963 T9619 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1732965 T9619
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1732965 T9619
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1732965 T9619 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1732966 T9619
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1732966 T9619
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1732966 T9619 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 1732967 T9619 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1732967 T9619 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/control/data/
[junit4] 2> 1732967 T9619 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1fb0e63
[junit4] 2> 1732968 T9619 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/control/data
[junit4] 2> 1732968 T9619 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/control/data/index/
[junit4] 2> 1732968 T9619 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 1732969 T9619 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/control/data/index
[junit4] 2> 1732970 T9619 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8039486683789457]
[junit4] 2> 1732972 T9619 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/index-MMapDirectory-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@17b682f),segFN=segments_1,generation=1}
[junit4] 2> 1732972 T9619 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1732975 T9619 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1732975 T9619 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1732976 T9619 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1732976 T9619 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1732976 T9619 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1732976 T9619 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1732977 T9619 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1732977 T9619 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1732977 T9619 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1732978 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1732979 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1732979 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1732979 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1732980 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1732981 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1732981 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1732982 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1732982 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1732983 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1732983 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1732984 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1732984 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1732985 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1732985 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1732986 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1732986 T9619 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1732987 T9619 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1732989 T9619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1732990 T9619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1732991 T9619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1732992 T9619 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1732992 T9619 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1732994 T9619 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1732994 T9619 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1732994 T9619 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.22745914620014818]
[junit4] 2> 1733005 T9619 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/index-MMapDirectory-001
lockFactory=org.apache.lucene.store.NativeFSLockFactory@17b682f),segFN=segments_1,generation=1}
[junit4] 2> 1733005 T9619 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1733006 T9619 oass.SolrIndexSearcher.<init> Opening
Searcher@dde48a[collection1] main
[junit4] 2> 1733006 T9619 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 1733007 T9619 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 1733007 T9619 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1733008 T9619
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1733008 T9619 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1733008 T9619 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1733009 T9619
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1733009 T9619 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1733009 T9619 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1733009 T9619 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1733010 T9620 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@dde48a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1733011 T9619 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1733012 T9623 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:35104/uo_r/g
collection:control_collection shard:shard1
[junit4] 2> 1733012 T9578 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1733012 T9578 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1733012 T9623 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1733013 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1733015 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1733015 T9626 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@71a121 name:ZooKeeperConnection
Watcher:127.0.0.1:59691/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1733016 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1733017 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1733018 T9578
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1733020 T9623 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 1733020 T9578 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 1733021 T9578 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 1733022 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1733022 T9623
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1733022 T9615 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1733023 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1733023 T9623 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1230 name=collection1
org.apache.solr.core.SolrCore@19e2f64
url=http://127.0.0.1:35104/uo_r/g/collection1 node=127.0.0.1:35104_uo_r%2Fg
C1230_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:35104/uo_r/g, node_name=127.0.0.1:35104_uo_r%2Fg,
state=down}
[junit4] 2> 1733024 T9623 C1230 P35104 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:35104/uo_r/g/collection1/
[junit4] 2> 1733025 T9623 C1230 P35104 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1733025 T9623 C1230 P35104 oasc.SyncStrategy.syncToMe
http://127.0.0.1:35104/uo_r/g/collection1/ has no replicas
[junit4] 2> 1733026 T9623 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:35104/uo_r/g/collection1/ shard1
[junit4] 2> 1733026 T9623 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 1733026 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 1733028 T9629 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1627542 name:ZooKeeperConnection
Watcher:127.0.0.1:59691/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1733029 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1733030 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1733031 T9615 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1733032 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:35104/uo_r/g";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 1733034 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":2} current state version: 1
[junit4] 2> 1733035 T9616 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 1733035 T9615 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> 1733039 T9616 oasco.ZkStateWriter.writePendingUpdates going to
create_collection /collections/collection1/state.json
[junit4] 2> 1733052 T9626 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> 1733057 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1733082 T9623 oasc.ZkController.register We are
http://127.0.0.1:35104/uo_r/g/collection1/ and leader is
http://127.0.0.1:35104/uo_r/g/collection1/
[junit4] 2> 1733083 T9623 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:35104/uo_r/g
[junit4] 2> 1733083 T9623 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1733083 T9623 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 1733083 T9623 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1733084 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1733086 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:35104/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:35104_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1733086 T9616 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:35104/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:35104_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1733189 T9626 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> 1733189 T9630 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> 1733345 T9578 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 1733346 T9578 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1733348 T9578 oejs.AbstractConnector.doStart Started
[email protected]:59373
[junit4] 2> 1733348 T9578 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@d25a40
[junit4] 2> 1733349 T9578 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1733349 T9578 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-004
[junit4] 2> 1733350 T9578 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-004/'
[junit4] 2> 1733368 T9578 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-004/solr.xml
[junit4] 2> 1733378 T9578 oasc.CoreContainer.<init> New CoreContainer
2129001
[junit4] 2> 1733379 T9578 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-004/]
[junit4] 2> 1733379 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1733380 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1733380 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1733381 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1733381 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1733381 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1733382 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1733382 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1733382 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1733383 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1733383 T9578 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1733384 T9578 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1733384 T9578 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1733384 T9578 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1733385 T9578 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59691/solr
[junit4] 2> 1733385 T9578 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1733386 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1733386 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1733388 T9642 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@94a56f name:ZooKeeperConnection
Watcher:127.0.0.1:59691 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1733388 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1733389 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1733391 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1733392 T9645 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@19705d name:ZooKeeperConnection
Watcher:127.0.0.1:59691/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1733392 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1733397 T9578
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1734401 T9578 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59373_uo_r%2Fg
[junit4] 2> 1734403 T9578 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:59373_uo_r%2Fg
[junit4] 2> 1734407 T9578 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1734412 T9646 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1734412 T9646 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1734413 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1734414 T9646 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1734414 T9646 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1734414 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59373/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:59373_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1734415 T9646 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1734415 T9616 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59373/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:59373_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1734416 T9616 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1734416 T9616 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 1734417 T9646 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 0
[junit4] 2> 1734417 T9646 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1734519 T9616 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 0
[junit4] 2> 1734520 T9645 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1734521 T9645 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 1
[junit4] 2> 1735417 T9646 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1735418 T9646 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1735419 T9646 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1735419 T9646 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1735420 T9646 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1735420 T9646 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-004/collection1/'
[junit4] 2> 1735421 T9646 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 1735421 T9646 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 1735422 T9646 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 1735438 T9646 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1735441 T9646 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1735446 T9646 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1735453 T9646 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 1735466 T9646 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1735467 T9646 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1735474 T9646 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1735606 T9646 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1735611 T9646 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1735613 T9646 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1735623 T9646 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1735626 T9646 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1735629 T9646 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1735631 T9646
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1735631 T9646
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1735632 T9646 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1735633 T9646
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1735633 T9646
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1735634 T9646 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1735634 T9646 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1735634 T9646 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty1/
[junit4] 2> 1735635 T9646 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1fb0e63
[junit4] 2> 1735635 T9646 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty1
[junit4] 2> 1735636 T9646 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty1/index/
[junit4] 2> 1735636 T9646 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 1735637 T9646 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty1/index
[junit4] 2> 1735637 T9646 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8039486683789457]
[junit4] 2> 1735639 T9646 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/index-MMapDirectory-002
lockFactory=org.apache.lucene.store.NativeFSLockFactory@17b682f),segFN=segments_1,generation=1}
[junit4] 2> 1735639 T9646 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1735643 T9646 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 1735643 T9646 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 1735643 T9646 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1735644 T9646 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 1735644 T9646 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1735644 T9646 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1735645 T9646 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1735645 T9646 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1735645 T9646 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1735646 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1735646 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1735647 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1735648 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1735648 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1735649 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1735651 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1735652 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1735653 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1735655 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1735656 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1735657 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1735658 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1735659 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1735660 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1735662 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1735663 T9646 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 1735664 T9646 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1735667 T9646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1735668 T9646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1735670 T9646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1735671 T9646 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1735672 T9646 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1735673 T9646 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 1735674 T9646 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 1735675 T9646 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=35, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.22745914620014818]
[junit4] 2> 1735678 T9646 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/index-MMapDirectory-002
lockFactory=org.apache.lucene.store.NativeFSLockFactory@17b682f),segFN=segments_1,generation=1}
[junit4] 2> 1735678 T9646 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 1735679 T9646 oass.SolrIndexSearcher.<init> Opening
Searcher@7ca192[collection1] main
[junit4] 2> 1735679 T9646 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1735680 T9646 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1735680 T9646 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1735681 T9646
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1735681 T9646 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 1735682 T9646 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1735682 T9646
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1735683 T9646 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1735683 T9646 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 1735683 T9646 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 1735685 T9647 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@7ca192[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1735685 T9646 oasc.CoreContainer.registerCore registering
core: collection1
[junit4] 2> 1735686 T9650 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:59373/uo_r/g collection:collection1
shard:shard2
[junit4] 2> 1735687 T9578 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1735687 T9578 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 1735701 T9650 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 1735705 T9650 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 1735706 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1735707 T9650
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 1735707 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> 1735707 T9650 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1231 name=collection1
org.apache.solr.core.SolrCore@ad656b
url=http://127.0.0.1:59373/uo_r/g/collection1 node=127.0.0.1:59373_uo_r%2Fg
C1231_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:59373/uo_r/g, node_name=127.0.0.1:59373_uo_r%2Fg,
state=down}
[junit4] 2> 1735707 T9650 C1231 P59373 oasc.SyncStrategy.sync Sync
replicas to http://127.0.0.1:59373/uo_r/g/collection1/
[junit4] 2> 1735708 T9650 C1231 P59373 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 1735708 T9616 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 1
[junit4] 2> 1735708 T9650 C1231 P59373 oasc.SyncStrategy.syncToMe
http://127.0.0.1:59373/uo_r/g/collection1/ has no replicas
[junit4] 2> 1735709 T9650 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:59373/uo_r/g/collection1/ shard2
[junit4] 2> 1735709 T9645 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1735709 T9650 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 1735710 T9645 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 2
[junit4] 2> 1735713 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1735714 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:59373/uo_r/g";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 1735715 T9616 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 2
[junit4] 2> 1735716 T9645 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1735717 T9645 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 3
[junit4] 2> 1735765 T9650 oasc.ZkController.register We are
http://127.0.0.1:59373/uo_r/g/collection1/ and leader is
http://127.0.0.1:59373/uo_r/g/collection1/
[junit4] 2> 1735765 T9650 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:59373/uo_r/g
[junit4] 2> 1735766 T9650 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 1735766 T9650 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 1735766 T9650 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1735767 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1735768 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59373/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:59373_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1735769 T9616 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:59373/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:59373_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1735770 T9616 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 3
[junit4] 2> 1735771 T9645 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1735773 T9650 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1735774 T9645 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1736079 T9578 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 1736080 T9578 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1736083 T9578 oejs.AbstractConnector.doStart Started
[email protected]:52178
[junit4] 2> 1736083 T9578 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@d25a40
[junit4] 2> 1736084 T9578 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 1736085 T9578 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-005
[junit4] 2> 1736085 T9578 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-005/'
[junit4] 2> 1736114 T9578 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-005/solr.xml
[junit4] 2> 1736159 T9578 oasc.CoreContainer.<init> New CoreContainer
17362235
[junit4] 2> 1736160 T9578 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-005/]
[junit4] 2> 1736161 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 1736161 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 1736162 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 1736162 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 1736163 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 1736163 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 1736164 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 1736165 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 1736165 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 1736166 T9578 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 1736167 T9578 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1736167 T9578 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1736168 T9578 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1736169 T9578 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1736169 T9578 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:59691/solr
[junit4] 2> 1736170 T9578 oasc.ZkController.checkChrootPath zkHost
includes chroot
[junit4] 2> 1736170 T9578
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 1736172 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1736173 T9662 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1d8ccf6 name:ZooKeeperConnection
Watcher:127.0.0.1:59691 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 1736173 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1736174 T9578 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 1736176 T9578 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 1736177 T9665 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@284e37 name:ZooKeeperConnection
Watcher:127.0.0.1:59691/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 1736178 T9578 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 1736183 T9578
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 1737188 T9578 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52178_uo_r%2Fg
[junit4] 2> 1737189 T9578 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:52178_uo_r%2Fg
[junit4] 2> 1737193 T9578 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1737197 T9666 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 1737198 T9666 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 1737199 T9630 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1737199 T9666 oasc.ZkController.preRegister Registering watch
for external collection collection1
[junit4] 2> 1737200 T9666 oascc.ZkStateReader.addZkWatch addZkWatch
collection1
[junit4] 2> 1737200 T9616 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52178/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:52178_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1737200 T9666 oascc.ZkStateReader.addZkWatch Updating
collection state at /collections/collection1/state.json from ZooKeeper...
[junit4] 2> 1737201 T9616 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:52178/uo_r/g";,
[junit4] 2> "node_name":"127.0.0.1:52178_uo_r%2Fg",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1737201 T9616 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 1737201 T9616 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 1737201 T9666 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 4
[junit4] 2> 1737202 T9666 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 1737202 T9616 oasco.ZkStateWriter.writePendingUpdates going to
update_collection /collections/collection1/state.json version: 4
[junit4] 2> 1737203 T9665 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1737203 T9645 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json, has occurred - updating...
[junit4] 2> 1737203 T9665 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1737204 T9645 oascc.ZkStateReader.updateWatchedCollection
Updating data for collection1 to ver 5
[junit4] 2> 1738202 T9666 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 1738203 T9666 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 1738205 T9666 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 1738205 T9666 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 1738206 T9666 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 1738206 T9666 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-005/collection1/'
[junit4] 2> 1738207 T9666 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-005/collection1/lib/.svn/'
to classloader
[junit4] 2> 1738207 T9666 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-005/collection1/lib/classes/'
to classloader
[junit4] 2> 1738207 T9666 oasc.SolrResourceLoader.replaceClassLoader
Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading%2061A64DEB9096C407-001/tempDir-005/collection1/lib/README'
to classloader
[junit4] 2> 1738225 T9666 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 1738227 T9666 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 1738232 T9666 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 1738238 T9666 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 5.1.0
[junit4] 2> 1738252 T9666 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 1738253 T9666 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 1738265 T9666 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 1738426 T9666 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1738431 T9666 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 1738433 T9666 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1738442 T9666 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1738445 T9666 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 1738447 T9666 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1738448 T9666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1738448 T9666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1738448 T9666 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1738449 T9666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 1738449 T9666
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 1738450 T9666 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 1738450 T9666 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1738450 T9666 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-005/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty2/
[junit4] 2> 1738451 T9666 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1fb0e63
[junit4] 2> 1738451 T9666 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty2
[junit4] 2> 1738452 T9666 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty2/index/
[junit4] 2> 1738452 T9666 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 1738453 T9666 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.core.TestDynamicLoading
61A64DEB9096C407-001/tempDir-002/jetty2/index
[junit4] 2> 1738453 T9666 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB
[...truncated too long message...]
9760 oascc.ZkStateReader$7.process WARN ZooKeeper watch triggered, but Solr
cannot talk to ZK
[junit4] 2> 1772244 T9578 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/uo_r/g,null}
[junit4] 2> 1772433 T9578 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 1772435 T9578 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:59691 59691
[junit4] 2> 1773246 T9590 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:59691 59691
[junit4] 2> 1773247 T9590 oasc.ZkTestServer$ZKServerMain.runFromConfig
WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/configs/.system
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1773248 T9578 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
35104
[junit4] 2> 1773248 T9578 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 1773249 T9578 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:59691 59691
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading
-Dtests.method=testDistribSearch -Dtests.seed=61A64DEB9096C407
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_ME_#Latn
-Dtests.timezone=ACT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 42.1s J0 | TestDynamicLoading.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not successfully
add blob after 150 attempts. Expecting 2 items. time elapsed 15,740 output
for url is {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":1},
[junit4] > "response":{
[junit4] > "numFound":1,
[junit4] > "start":0,
[junit4] > "docs":[{
[junit4] > "id":"test/1",
[junit4] > "md5":"e7832b5fcc813969d3da5310cdf8445d",
[junit4] > "blobName":"test",
[junit4] > "version":1,
[junit4] > "timestamp":"2015-01-14T13:24:07.147Z",
[junit4] > "size":5296}]}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([61A64DEB9096C407:E040C3F3E7C9A43B]:0)
[junit4] > at
org.apache.solr.handler.TestBlobHandler.postAndCheck(TestBlobHandler.java:150)
[junit4] > at
org.apache.solr.core.TestDynamicLoading.dynamicLoading(TestDynamicLoading.java:114)
[junit4] > at
org.apache.solr.core.TestDynamicLoading.doTest(TestDynamicLoading.java:70)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1773260 T9578 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.core.TestDynamicLoading
61A64DEB9096C407-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{blobName=Lucene50(blocksize=128), size=BlockTreeOrds(blocksize=128),
_version_=Lucene50(blocksize=128),
id=PostingsFormat(name=LuceneVarGapDocFreqInterval),
version=Lucene50(blocksize=128), md5=Lucene50(blocksize=128),
timestamp=Lucene50(blocksize=128)}, docValues:{},
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {},
locale=sr_ME_#Latn, timezone=ACT
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.9.0-ea
(32-bit)/cpus=8,threads=1,free=89055744,total=289406976
[junit4] 2> NOTE: All tests run in this JVM: [TestPhraseSuggestions,
DocumentAnalysisRequestHandlerTest, DeleteReplicaTest, NoCacheHeaderTest,
TestPartialUpdateDeduplication, TestNonDefinedSimilarityFactory,
TestConfigReload, CircularListTest, RemoteQueryErrorTest, RollingRestartTest,
MultiTermTest, RequestHandlersTest, TestLRUCache, PingRequestHandlerTest,
SuggesterWFSTTest, AliasIntegrationTest, QueryResultKeyTest,
LukeRequestHandlerTest, TestArbitraryIndexDir, TestDocumentBuilder,
SyncSliceTest, ResponseHeaderTest, HdfsBasicDistributedZk2Test,
TestReloadAndDeleteDocs, AssignTest, TestOrdValues, CoreAdminHandlerTest,
TestCoreContainer, TestIndexSearcher, CacheHeaderTest, TestChildDocTransformer,
DirectSolrConnectionTest, UUIDFieldTest, TestCopyFieldCollectionResource,
UUIDUpdateProcessorFallbackTest, TestFieldTypeResource, TestStressVersions,
TestSolrDeletionPolicy2, TestBlobHandler, TestHashPartitioner,
CustomCollectionTest, TestStressReorder, TestBlendedInfixSuggestions,
SolrInfoMBeanTest, TestDFRSimilarityFactory, TestStressLucene,
PreAnalyzedFieldTest, OverseerTest, DateMathParserTest,
TestExtendedDismaxParser, TestManagedResourceStorage, TestGroupingSearch,
TestOmitPositions, TestBulkSchemaConcurrent, TestObjectReleaseTracker,
TestCollationField, TestUpdate, TestSweetSpotSimilarityFactory,
HardAutoCommitTest, SolrIndexConfigTest, SimpleFacetsTest, SSLMigrationTest,
ConnectionManagerTest, DistributedIntervalFacetingTest, TestCSVResponseWriter,
CoreAdminCreateDiscoverTest, TestRandomMergePolicy, CursorPagingTest,
DistributedSuggestComponentTest, DocValuesTest, OutputWriterTest, ZkCLITest,
ResourceLoaderTest, SolrPluginUtilsTest, TestQueryTypes, ClusterStateTest,
DeleteShardTest, TestStressRecovery, HttpPartitionTest,
DistributedFacetPivotLargeTest, TestManagedSchemaFieldTypeResource,
TestQuerySenderNoQuery, TestQuerySenderListener, HighlighterConfigTest,
TestCollapseQParserPlugin, DistributedQueryComponentCustomSortTest,
AsyncMigrateRouteKeyTest, DistributedQueryComponentOptimizationTest,
TestSearcherReuse, PluginInfoTest, DocValuesMissingTest, TestInitParams,
DocValuesMultiTest, DateFieldTest, TestStressUserVersions,
ClusterStateUpdateTest, TestSolrConfigHandlerConcurrent,
TestReplicationHandler, TestCursorMarkWithoutUniqueKey,
TestIndexingPerformance, TestSolrConfigHandlerCloud,
TestWordDelimiterFilterFactory, TestFastWriter,
DistributedSpellCheckComponentTest, TestQueryUtils, AnalyticsQueryTest,
TestIBSimilarityFactory, TestOverriddenPrefixQueryForCustomFieldType,
TestInitQParser, CloudMLTQParserTest, TestFunctionQuery,
OpenCloseCoreStressTest, LeaderElectionIntegrationTest, RecoveryZkTest,
LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, TestFaceting,
TermVectorComponentDistributedTest, TestRealTimeGet, SolrCoreTest,
SolrCmdDistributorTest, QueryElevationComponentTest, PeerSyncTest,
BadIndexSchemaTest, TestSort, TestFiltering, SolrIndexSplitterTest,
SimplePostToolTest, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest,
TestFoldingMultitermQuery, TestCSVLoader, WordBreakSolrSpellCheckerTest,
TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest,
SortByFunctionTest, TestSolrDeletionPolicy1, TestWriterPerf,
TermsComponentTest, DocumentBuilderTest, RequiredFieldsTest,
IndexSchemaRuntimeFieldTest, TestJmxIntegration, MBeansHandlerTest,
UniqFieldsUpdateProcessorFactoryTest, TestPHPSerializedResponseWriter,
TestComponentsName, TestBinaryResponseWriter, TestLFUCache, UpdateParamsTest,
ResponseLogComponentTest, NumericFieldsTest, TestElisionMultitermQuery,
TestConfig, NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest,
TestCharFilters, TestXIncludeConfig, TestLMDirichletSimilarityFactory,
TestDefaultSimilarityFactory, TestFastOutputStream, URLClassifyProcessorTest,
PrimUtilsTest, DOMUtilTest, SliceStateTest, SolrTestCaseJ4Test,
TestTolerantSearch, CloudExitableDirectoryReaderTest,
DeleteInactiveReplicaTest, DistributedQueueTest, ExternalCollectionsTest,
LeaderInitiatedRecoveryOnCommitTest, OverseerRolesTest, OverseerStatusTest,
SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest,
SolrXmlInZkTest, TestCloudInspectUtil, TestCloudPivotFacet,
TestDistribDocBasedVersion, TestMiniSolrCloudCluster,
TestShortCircuitedRequests, TriLevelCompositeIdRoutingTest,
VMParamsZkACLAndCredentialsProvidersTest, HdfsCollectionsAPIDistributedZkTest,
HdfsRecoveryZkTest, HdfsSyncSliceTest, HdfsUnloadDistributedZkTest,
HdfsWriteToMultipleCollectionsTest, StressHdfsTest, ZkStateWriterTest,
CachingDirectoryFactoryTest, ExitableDirectoryReaderTest,
HdfsDirectoryFactoryTest, TestConfigOverlay, TestConfigSets, TestDynamicLoading]
[junit4] Completed on J0 in 42.85s, 2 tests, 1 failure <<< FAILURES!
[...truncated 188 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970:
There were test failures: 462 suites, 1871 tests, 1 failure, 47 ignored (21
assumptions)
Total time: 79 minutes 26 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.9.0-ea-b44 -client
-XX:+UseG1GC
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]
