Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11595/
Java: 64bit/jdk1.8.0_40-ea-b20 -XX:-UseCompressedOops -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,669 output for url is { "responseHeader":{ "status":0,
"QTime":1}, "response":{ "numFound":1, "start":0, "docs":[{
"id":"test/1", "md5":"1c045b1c3b68e3855b2ee205c2960ad",
"blobName":"test", "version":1,
"timestamp":"2015-01-13T23:55:38.216Z", "size":5318}]}}
Stack Trace:
java.lang.AssertionError: Could not successfully add blob after 150 attempts.
Expecting 2 items. time elapsed 15,669 output for url is {
"responseHeader":{
"status":0,
"QTime":1},
"response":{
"numFound":1,
"start":0,
"docs":[{
"id":"test/1",
"md5":"1c045b1c3b68e3855b2ee205c2960ad",
"blobName":"test",
"version":1,
"timestamp":"2015-01-13T23:55:38.216Z",
"size":5318}]}}
at
__randomizedtesting.SeedInfo.seed([311CE47160590CA5:B0FA6A6917066C99]: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.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
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 9089 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/init-core-data-001
[junit4] 2> 409675 T1600 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (true)
[junit4] 2> 409675 T1600 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 409677 T1600 oas.SolrTestCaseJ4.setUp ###Starting testDown
[junit4] 2> 409677 T1600 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 409678 T1601 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 409778 T1600 oasc.ZkTestServer.run start zk server on
port:45329
[junit4] 2> 409778 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 409779 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 409784 T1608 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7e12b121
name:ZooKeeperConnection Watcher:127.0.0.1:45329 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 409785 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 409785 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 409785 T1600 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 409788 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 409788 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 409790 T1611 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@36d2a83e
name:ZooKeeperConnection Watcher:127.0.0.1:45329/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 409790 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 409791 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 409791 T1600 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 409793 T1600 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 409794 T1600 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 409795 T1600 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 409796 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 409796 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 409798 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 409798 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 409800 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 409800 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 409801 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 409801 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 409802 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 409803 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 409804 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 409804 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 409816 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 409817 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 409819 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 409819 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 409820 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 409820 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 409822 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 409822 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 409823 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 409823 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 409826 T1600 oas.SolrTestCaseJ4.tearDown ###Ending testDown
[junit4] 2> 409826 T1600 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:45329 45329
[junit4] 2> 410371 T1601 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:45329 45329
[junit4] 2> 410373 T1600 oas.SolrTestCaseJ4.tearDown ###Ending testDown
[junit4] 2> 410373 T1600 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:45329 45329
[junit4] 2> 410374 T1600 oas.SolrTestCaseJ4.tearDown ###Ending testDown
[junit4] 2> 410374 T1600 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:45329 45329
[junit4] 2> 410379 T1600 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 410380 T1600 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 410380 T1612 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 410480 T1600 oasc.ZkTestServer.run start zk server on
port:51711
[junit4] 2> 410481 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 410481 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 410486 T1619 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@22266dc8
name:ZooKeeperConnection Watcher:127.0.0.1:51711 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410487 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 410487 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 410487 T1600 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 410489 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 410490 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 410497 T1622 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5f5df6dc
name:ZooKeeperConnection Watcher:127.0.0.1:51711/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410498 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 410498 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 410498 T1600 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 410501 T1600 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 410502 T1600 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 410503 T1600 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 410504 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 410504 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 410506 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 410506 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 410508 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 410508 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 410509 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 410510 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 410511 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 410511 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 410512 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 410513 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 410514 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 410514 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 410515 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 410515 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 410517 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 410517 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 410518 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 410518 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 410520 T1600 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 410520 T1600 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 410764 T1600 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 410783 T1600 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@a0cf17d{/,null,AVAILABLE}
[junit4] 2> 410786 T1600 oejs.AbstractConnector.doStart Started
ServerConnector@186bbbba{HTTP/1.1}{127.0.0.1:55326}
[junit4] 2> 410786 T1600 oejs.Server.doStart Started @412030ms
[junit4] 2> 410787 T1600 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 410787 T1600 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 410787 T1600 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-003
[junit4] 2> 410788 T1600 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-003/'
[junit4] 2> 410798 T1600 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-003/solr.xml
[junit4] 2> 410807 T1600 oasc.CoreContainer.<init> New CoreContainer
233968674
[junit4] 2> 410807 T1600 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-003/]
[junit4] 2> 410808 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 410808 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 410808 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 410809 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 410809 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 410809 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 410809 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 410809 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 410810 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 410810 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 410810 T1600 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 410810 T1600 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 410811 T1600 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 410811 T1600 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 410811 T1600 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:51711/solr
[junit4] 2> 410811 T1600 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 410812 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 410812 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 410814 T1636 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@388635e name:ZooKeeperConnection
Watcher:127.0.0.1:51711 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 410815 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 410815 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 410817 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 410820 T1639 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@68ecc49b
name:ZooKeeperConnection Watcher:127.0.0.1:51711/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410820 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 410822 T1600 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 410824 T1600 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 410825 T1600 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 410827 T1600 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 410829 T1600 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 410830 T1600 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 410831 T1600 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55326_
[junit4] 2> 410832 T1600 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:55326_
[junit4] 2> 410833 T1600 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 410834 T1600 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 410835 T1600 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 410835 T1600 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:55326_
[junit4] 2> 410836 T1600 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 410837 T1600 oasc.Overseer.start Overseer
(id=93139325610229763-127.0.0.1:55326_-n_0000000000) starting
[junit4] 2> 410838 T1600 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 410841 T1600 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 410842 T1641 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 410842 T1600 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 410844 T1600 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 410845 T1600
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 410847 T1640 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 410848 T1643 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 410848 T1643 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 410849 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 410849 T1643 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 410850 T1640 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:55326";,
[junit4] 2> "node_name":"127.0.0.1:55326_",
[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> 410851 T1640 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:55326";,
[junit4] 2> "node_name":"127.0.0.1:55326_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 410851 T1640 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 410851 T1640 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 410852 T1639 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> 411849 T1643 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 411849 T1643 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 411850 T1643 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 411850 T1643 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 411851 T1643 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 411851 T1643 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-003/collection1/'
[junit4] 2> 411851 T1643 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 411851 T1643 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 411852 T1643 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 411861 T1643 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 411862 T1643 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 411866 T1643 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 411870 T1643 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 411879 T1643 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 411879 T1643 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 411884 T1643 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 411975 T1643 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 411978 T1643 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 411979 T1643 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 411985 T1643 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 411987 T1643 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 411989 T1643 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 411989 T1643
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 411989 T1643
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 411989 T1643 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 411990 T1643
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 411990 T1643
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 411990 T1643 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 411990 T1643 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 411991 T1643 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/control/data/
[junit4] 2> 411991 T1643 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@172b2815
[junit4] 2> 411991 T1643 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/control/data
[junit4] 2> 411991 T1643 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/control/data/index/
[junit4] 2> 411992 T1643 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 411992 T1643 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/control/data/index
[junit4] 2> 411992 T1643 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=38, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=22.0,
floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=22.055732698967546,
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42370587619276046
[junit4] 2> 411993 T1643 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1813d311
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@616a0204),segFN=segments_1,generation=1}
[junit4] 2> 411993 T1643 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 411995 T1643 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 411995 T1643 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 411995 T1643 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 411995 T1643 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 411995 T1643 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 411995 T1643 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 411996 T1643 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 411996 T1643 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 411996 T1643 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 411996 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 411997 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 411997 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 411997 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 411997 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 411997 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 411998 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 411998 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 411998 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 411999 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 411999 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 411999 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 411999 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 412000 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 412000 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 412000 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 412000 T1643 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 412001 T1643 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 412002 T1643 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 412003 T1643 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 412003 T1643 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 412004 T1643 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 412004 T1643 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 412005 T1643 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 412005 T1643 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 412006 T1643 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=368075249,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 412006 T1643 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1813d311
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@616a0204),segFN=segments_1,generation=1}
[junit4] 2> 412006 T1643 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 412006 T1643 oass.SolrIndexSearcher.<init> Opening
Searcher@5682bc7d[collection1] main
[junit4] 2> 412007 T1643 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 412007 T1643 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 412007 T1643 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 412008 T1643
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 412008 T1643 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 412008 T1643 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 412008 T1643
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 412008 T1643 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 412008 T1643 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 412009 T1643 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 412009 T1644 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5682bc7d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 412010 T1643 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 412010 T1647 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:55326 collection:control_collection
shard:shard1
[junit4] 2> 412010 T1600 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 412011 T1600 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 412011 T1647 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 412011 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 412012 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 412015 T1650 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@664e49bc
name:ZooKeeperConnection Watcher:127.0.0.1:51711/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 412015 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 412016 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 412017 T1600
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 412017 T1647 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 412018 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 412019 T1647
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 412019 T1647 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C400 name=collection1
org.apache.solr.core.SolrCore@2feac203 url=http://127.0.0.1:55326/collection1
node=127.0.0.1:55326_ C400_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:55326,
node_name=127.0.0.1:55326_, state=down}
[junit4] 2> 412019 T1647 C400 P55326 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:55326/collection1/
[junit4] 2> 412019 T1640 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> 412019 T1600 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 412019 T1647 C400 P55326 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 412020 T1647 C400 P55326 oasc.SyncStrategy.syncToMe
http://127.0.0.1:55326/collection1/ has no replicas
[junit4] 2> 412020 T1647 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:55326/collection1/ shard1
[junit4] 2> 412020 T1647 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 412022 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 412022 T1640 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:55326";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 412124 T1639 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> 412124 T1650 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> 412173 T1647 oasc.ZkController.register We are
http://127.0.0.1:55326/collection1/ and leader is
http://127.0.0.1:55326/collection1/
[junit4] 2> 412173 T1647 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:55326
[junit4] 2> 412173 T1647 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 412173 T1647 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 412173 T1647 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 412174 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 412176 T1640 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:55326";,
[junit4] 2> "node_name":"127.0.0.1:55326_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 412176 T1640 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:55326";,
[junit4] 2> "node_name":"127.0.0.1:55326_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 412278 T1639 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> 412278 T1650 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> 412281 T1600 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 412282 T1600 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 412284 T1600 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@4b24e4d5{/,null,AVAILABLE}
[junit4] 2> 412285 T1600 oejs.AbstractConnector.doStart Started
ServerConnector@5f34595b{HTTP/1.1}{127.0.0.1:37857}
[junit4] 2> 412285 T1600 oejs.Server.doStart Started @413528ms
[junit4] 2> 412285 T1600 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 412286 T1600 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 412286 T1600 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-004
[junit4] 2> 412286 T1600 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-004/'
[junit4] 2> 412296 T1600 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-004/solr.xml
[junit4] 2> 412304 T1600 oasc.CoreContainer.<init> New CoreContainer
258023365
[junit4] 2> 412305 T1600 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-004/]
[junit4] 2> 412305 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 412305 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 412305 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 412306 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 412306 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 412306 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 412306 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 412306 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 412307 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 412307 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 412307 T1600 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 412308 T1600 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 412308 T1600 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 412308 T1600 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 412308 T1600 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:51711/solr
[junit4] 2> 412308 T1600 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 412309 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 412309 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 412312 T1664 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1611183e
name:ZooKeeperConnection Watcher:127.0.0.1:51711 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 412312 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 412312 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 412314 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 412316 T1667 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@10146c55
name:ZooKeeperConnection Watcher:127.0.0.1:51711/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 412316 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 412320 T1600
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 413322 T1600 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37857_
[junit4] 2> 413323 T1600 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:37857_
[junit4] 2> 413326 T1600 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 413330 T1668 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 413330 T1668 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 413330 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 413330 T1668 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 413331 T1640 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:37857";,
[junit4] 2> "node_name":"127.0.0.1:37857_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 413332 T1640 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:37857";,
[junit4] 2> "node_name":"127.0.0.1:37857_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 413332 T1640 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 413332 T1640 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 413434 T1639 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 413434 T1650 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 413435 T1667 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414331 T1668 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 414331 T1668 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 414332 T1668 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 414332 T1668 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 414332 T1668 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 414332 T1668 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-004/collection1/'
[junit4] 2> 414333 T1668 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 414333 T1668 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 414333 T1668 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 414343 T1668 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 414344 T1668 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 414348 T1668 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 414352 T1668 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 414363 T1668 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 414364 T1668 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 414368 T1668 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 414454 T1668 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 414457 T1668 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 414459 T1668 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 414475 T1668 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 414477 T1668 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 414478 T1668 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 414479 T1668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 414479 T1668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 414479 T1668 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 414480 T1668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 414480 T1668
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 414480 T1668 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 414480 T1668 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 414481 T1668 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty1/
[junit4] 2> 414481 T1668 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@172b2815
[junit4] 2> 414481 T1668 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty1
[junit4] 2> 414482 T1668 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty1/index/
[junit4] 2> 414482 T1668 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 414482 T1668 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty1/index
[junit4] 2> 414482 T1668 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=38, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=22.0,
floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=22.055732698967546,
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42370587619276046
[junit4] 2> 414483 T1668 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1273fefc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3159a0eb),segFN=segments_1,generation=1}
[junit4] 2> 414483 T1668 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 414485 T1668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 414486 T1668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 414486 T1668 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 414486 T1668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 414486 T1668 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 414486 T1668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 414487 T1668 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 414487 T1668 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 414487 T1668 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 414487 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 414488 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 414488 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 414488 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 414488 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 414489 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 414489 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 414489 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 414490 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 414490 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 414491 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 414491 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 414491 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 414491 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 414492 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 414492 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 414492 T1668 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 414493 T1668 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 414494 T1668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 414495 T1668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 414495 T1668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 414496 T1668 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 414496 T1668 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 414497 T1668 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 414497 T1668 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 414499 T1668 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=368075249,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 414499 T1668 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1273fefc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3159a0eb),segFN=segments_1,generation=1}
[junit4] 2> 414499 T1668 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 414499 T1668 oass.SolrIndexSearcher.<init> Opening
Searcher@32eee54[collection1] main
[junit4] 2> 414500 T1668 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 414500 T1668 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 414501 T1668 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 414501 T1668
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 414501 T1668 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 414501 T1668 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 414502 T1668
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 414502 T1668 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 414502 T1668 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 414502 T1668 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 414503 T1669 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@32eee54[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 414503 T1668 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 414504 T1672 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:37857 collection:collection1
shard:shard2
[junit4] 2> 414504 T1600 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 414504 T1600 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 414504 T1672 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 414509 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 414510 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 414510 T1672
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 414510 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 414510 T1640 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> ASYNC NEW_CORE C401 name=collection1
org.apache.solr.core.SolrCore@5469d188 url=http://127.0.0.1:37857/collection1
node=127.0.0.1:37857_ C401_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:37857,
node_name=127.0.0.1:37857_, state=down}
[junit4] 2> 414510 T1672 C401 P37857 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:37857/collection1/
[junit4] 2> 414511 T1672 C401 P37857 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 414511 T1672 C401 P37857 oasc.SyncStrategy.syncToMe
http://127.0.0.1:37857/collection1/ has no replicas
[junit4] 2> 414511 T1672 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:37857/collection1/ shard2
[junit4] 2> 414511 T1672 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 414513 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 414514 T1640 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:37857";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 414616 T1667 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414616 T1639 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414616 T1650 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414664 T1672 oasc.ZkController.register We are
http://127.0.0.1:37857/collection1/ and leader is
http://127.0.0.1:37857/collection1/
[junit4] 2> 414664 T1672 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:37857
[junit4] 2> 414664 T1672 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 414665 T1672 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 414665 T1672 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 414666 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 414666 T1640 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:37857";,
[junit4] 2> "node_name":"127.0.0.1:37857_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 414667 T1640 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:37857";,
[junit4] 2> "node_name":"127.0.0.1:37857_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 414743 T1600 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 414743 T1600 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 414745 T1600 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@78a84d72{/,null,AVAILABLE}
[junit4] 2> 414745 T1600 oejs.AbstractConnector.doStart Started
ServerConnector@6912e121{HTTP/1.1}{127.0.0.1:54479}
[junit4] 2> 414746 T1600 oejs.Server.doStart Started @415989ms
[junit4] 2> 414746 T1600 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 414746 T1600 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 414747 T1600 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-005
[junit4] 2> 414747 T1600 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-005/'
[junit4] 2> 414757 T1600 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-005/solr.xml
[junit4] 2> 414766 T1600 oasc.CoreContainer.<init> New CoreContainer
1882620637
[junit4] 2> 414766 T1600 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-005/]
[junit4] 2> 414766 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 414767 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 414767 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 414767 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 414767 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 414767 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 414768 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 414768 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 414768 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 414768 T1600 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 414768 T1639 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414769 T1667 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414769 T1650 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 414769 T1600 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 414769 T1600 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 414770 T1600 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 414770 T1600 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 414770 T1600 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:51711/solr
[junit4] 2> 414771 T1600 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 414771 T1600
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 414771 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 414773 T1686 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4b65a1f0
name:ZooKeeperConnection Watcher:127.0.0.1:51711 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 414774 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 414774 T1600 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 414776 T1600 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 414780 T1689 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1bd0322a
name:ZooKeeperConnection Watcher:127.0.0.1:51711/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 414780 T1600 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 414784 T1600
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 415786 T1600 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54479_
[junit4] 2> 415787 T1600 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:54479_
[junit4] 2> 415790 T1600 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 415792 T1690 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 415793 T1690 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 415793 T1690 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 415793 T1639 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 415794 T1640 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:54479";,
[junit4] 2> "node_name":"127.0.0.1:54479_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 415794 T1640 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:54479";,
[junit4] 2> "node_name":"127.0.0.1:54479_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 415795 T1640 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 415795 T1640 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 415898 T1667 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 415898 T1639 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 415898 T1650 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 415898 T1689 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 416793 T1690 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 416794 T1690 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 416794 T1690 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 416794 T1690 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 416795 T1690 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 416795 T1690 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-005/collection1/'
[junit4] 2> 416796 T1690 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-005/collection1/lib/.svn/'
to classloader
[junit4] 2> 416796 T1690 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-005/collection1/lib/classes/'
to classloader
[junit4] 2> 416796 T1690 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading%20311CE47160590CA5-001/tempDir-005/collection1/lib/README'
to classloader
[junit4] 2> 416805 T1690 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 416806 T1690 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 416810 T1690 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 416814 T1690 oasc.SolrConfig.<init> Using Lucene MatchVersion:
6.0.0
[junit4] 2> 416823 T1690 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 416824 T1690 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 416828 T1690 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 416928 T1690 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 416932 T1690 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 416933 T1690 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 416942 T1690 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 416945 T1690 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 416947 T1690 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 416948 T1690
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 416948 T1690
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 416948 T1690 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 416949 T1690
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 416949 T1690
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 416949 T1690 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 416949 T1690 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 416950 T1690 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-005/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty2/
[junit4] 2> 416950 T1690 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@172b2815
[junit4] 2> 416951 T1690 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty2
[junit4] 2> 416951 T1690 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty2/index/
[junit4] 2> 416952 T1690 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 416952 T1690 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001/tempDir-002/jetty2/index
[junit4] 2> 416953 T1690 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=38, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=22.0,
floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=22.055732698967546,
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42370587619276046
[junit4] 2> 416953 T1690 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@751f3474
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5fdfddb5),segFN=segments_1,generation=1}
[junit4] 2> 416954 T1690 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 416958 T1690 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 416958 T1690 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 416959 T1690 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 416959 T1690 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 416959 T1690 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 416960 T1690 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 416960 T1690 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 416960 T1690 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 416961 T1690 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 416961 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 416962 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 416962 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 416963 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 416963 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 416964 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 416965 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 416965 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 416966 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 416966 T1690 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 416967 T1690 oasc.RequestH
[...truncated too long message...]
it4] 2> 447037 T1736 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4] 2> 447037 T1736 oasc.SolrException.log ERROR There was a problem
trying to register as the leader:org.apache.solr.common.SolrException: Could
not register as the leader because creating the ephemeral registration node in
ZooKeeper failed
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:147)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:308)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:390)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:263)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /collections
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:293)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:290)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:290)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:485)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:454)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:441)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:139)
[junit4] 2> at
org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> ... 11 more
[junit4] 2>
[junit4] 2> 447553 T1797 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 447555 T1600 oejs.AbstractConnector.doStop Stopped
ServerConnector@683f7b51{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 447562 T1600 oejsh.ContextHandler.doStop Stopped
o.e.j.s.ServletContextHandler@49b64a62{/,null,UNAVAILABLE}
[junit4] 2> 447567 T1600 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 447568 T1600 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:51711 51711
[junit4] 2> 448340 T1612 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:51711 51711
[junit4] 2> 448343 T1612 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> 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/collection-queue-work
[junit4] 2> 4 /solr/overseer/queue
[junit4] 2>
[junit4] 2> 448344 T1600 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
55326
[junit4] 2> 448344 T1600 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 448344 T1600 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:51711 51711
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDynamicLoading
-Dtests.method=testDistribSearch -Dtests.seed=311CE47160590CA5
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_GT
-Dtests.timezone=Pacific/Pohnpei -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 38.0s J1 | TestDynamicLoading.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not successfully
add blob after 150 attempts. Expecting 2 items. time elapsed 15,669 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":"1c045b1c3b68e3855b2ee205c2960ad",
[junit4] > "blobName":"test",
[junit4] > "version":1,
[junit4] > "timestamp":"2015-01-13T23:55:38.216Z",
[junit4] > "size":5318}]}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([311CE47160590CA5:B0FA6A6917066C99]: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> 448363 T1600 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.core.TestDynamicLoading
311CE47160590CA5-001
[junit4] 2> 38692 T1599 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are:
codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=3, maxDocsPerChunk=779, blockSize=516),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION,
chunkSize=3, blockSize=516)),
sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=es_GT,
timezone=Pacific/Pohnpei
[junit4] 2> NOTE: Linux 3.13.0-43-generic amd64/Oracle Corporation
1.8.0_40-ea (64-bit)/cpus=8,threads=1,free=341891984,total=536870912
[junit4] 2> NOTE: All tests run in this JVM:
[DistributedTermsComponentTest, TestSolr4Spatial2, TestReload,
TestDocBasedVersionConstraints, TestFiltering, DisMaxRequestHandlerTest,
ChangedSchemaMergeTest, SpellCheckComponentTest, SpellPossibilityIteratorTest,
UpdateParamsTest, FieldMutatingUpdateProcessorTest, TestJoin, TestRTGBase,
BinaryUpdateRequestHandlerTest, TermVectorComponentDistributedTest,
TestMaxScoreQueryParser, TestRawResponseWriter, TestSolrXmlPersistence,
TestSolrIndexConfig, ShardRoutingCustomTest, TestConfigOverlay,
TestElisionMultitermQuery, StatsComponentTest, EnumFieldTest,
AddSchemaFieldsUpdateProcessorFactoryTest, TestManagedSchema,
TestNoOpRegenerator, UpdateRequestProcessorFactoryTest,
FastVectorHighlighterTest, IndexSchemaTest, EchoParamsTest,
TestJmxMonitoredMap, TestFieldTypeCollectionResource,
TestDynamicFieldCollectionResource, TestSolrJ, BufferStoreTest,
TestFreeTextSuggestions, TestOrdValues, MBeansHandlerTest,
TestDynamicFieldResource, TestAddFieldRealTimeGet,
StatelessScriptUpdateProcessorFactoryTest, QueryParsingTest,
DistributedQueueTest, TestLuceneMatchVersion, TimeZoneUtilsTest, TestDocSet,
PreAnalyzedUpdateProcessorTest, ExternalFileFieldSortTest, TestNRTOpen,
ShardSplitTest, WordBreakSolrSpellCheckerTest, OverseerStatusTest,
AnalysisErrorHandlingTest, BadIndexSchemaTest, TestFieldCollectionResource,
TestDynamicLoading]
[junit4] Completed on J1 in 38.96s, 2 tests, 1 failure <<< FAILURES!
[...truncated 1129 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:187: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1348:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:956:
There were test failures: 463 suites, 1870 tests, 1 failure, 47 ignored (21
assumptions)
Total time: 60 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_40-ea-b20
-XX:-UseCompressedOops -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]
