Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java7/4858/
1 tests failed.
REGRESSION:
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create
core [halfcollection_shard1_replica1] Caused by: Could not get shard id for
core: halfcollection_shard1_replica1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error
CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core:
halfcollection_shard1_replica1
at
__randomizedtesting.SeedInfo.seed([880535A34215B5C0:9E3BBBB354AD5FC]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:550)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11461 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/init-core-data-001
[junit4] 2> 262924 T1521 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(true) and clientAuth (true)
[junit4] 2> 262924 T1521 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 262931 T1521 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 262932 T1521 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 262933 T1522 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 263032 T1521 oasc.ZkTestServer.run start zk server on
port:44019
[junit4] 2> 263033 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 263034 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 263038 T1528 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@67a43b88
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 263038 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 263038 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 263039 T1521 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 263042 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 263043 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 263044 T1530 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3db0d04a
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 263044 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 263045 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 263045 T1521 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 263048 T1521 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 263050 T1521 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 263051 T1521 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 263053 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 263053 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 263056 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 263057 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 263158 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 263159 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 263161 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 263162 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 263163 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 263164 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 263166 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 263166 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 263168 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 263169 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 263170 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 263171 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 263173 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 263173 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 263175 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 263176 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 263177 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 263178 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 263180 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 263181 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 263182 T1532 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@41a12cff
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 263183 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 263183 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> Using old style solr.xml
[junit4] 2> 263185 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 263186 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 263187 T1534 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@707f6892
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 263188 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 263188 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 263188 T1521 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 263190 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 263191 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 263192 T1536 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@b6d68bb name:ZooKeeperConnection
Watcher:127.0.0.1:44019/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 263192 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 263193 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 263194 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf2/solrconfig.xml
[junit4] 2> 263194 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/solrconfig.xml
[junit4] 2> 263197 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf2/schema.xml
[junit4] 2> 263197 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/schema.xml
[junit4] 2> 263298 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 263299 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 263301 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf2/stopwords.txt
[junit4] 2> 263302 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/stopwords.txt
[junit4] 2> 263303 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf2/protwords.txt
[junit4] 2> 263304 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/protwords.txt
[junit4] 2> 263306 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf2/currency.xml
[junit4] 2> 263306 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/currency.xml
[junit4] 2> 263308 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf2/open-exchange-rates.json
[junit4] 2> 263309 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/open-exchange-rates.json
[junit4] 2> 263310 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 263311 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 263313 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf2/old_synonyms.txt
[junit4] 2> 263313 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/old_synonyms.txt
[junit4] 2> 263315 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf2/synonyms.txt
[junit4] 2> 263316 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/synonyms.txt
[junit4] 2> 263317 T1521 oasc.AbstractZkTestCase.putConfig put
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/elevate.xml
to /configs/conf2/elevate.xml
[junit4] 2> 263318 T1521 oascc.SolrZkClient.makePath makePath:
/configs/conf2/elevate.xml
[junit4] 2> 264655 T1521 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 264662 T1521 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 264665 T1521 oejs.AbstractConnector.doStart Started
[email protected]:44031
[junit4] 2> 264668 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 264669 T1521 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 264669 T1521 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002
[junit4] 2> 264670 T1521 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/'
[junit4] 2> 264714 T1521 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solr.xml
[junit4] 2> 264794 T1521 oasc.CoreContainer.<init> New CoreContainer
1138982704
[junit4] 2> 264795 T1521 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/]
[junit4] 2> 264796 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 264797 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 264797 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 264798 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 264798 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 264798 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 264799 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 264799 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 264800 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 264803 T1521 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 264805 T1521 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 264805 T1521 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 264806 T1521 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 264806 T1521 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:44019/solr
[junit4] 2> 264807 T1521 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 264807 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 264808 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 264810 T1547 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@55cd205c
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264811 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 264811 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 264813 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 264814 T1549 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@773d30eb
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264814 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 264815 T1521 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 264817 T1521 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 264818 T1521 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 264820 T1521 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 264822 T1521 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 264823 T1521 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 264824 T1521 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44031_
[junit4] 2> 264825 T1521 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44031_
[junit4] 2> 264827 T1521 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 264828 T1521 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 264829 T1521 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 264830 T1521 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:44031_
[junit4] 2> 264831 T1521 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 264832 T1521 oasc.Overseer.start Overseer
(id=92469363890192390-127.0.0.1:44031_-n_0000000000) starting
[junit4] 2> 264834 T1521 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 264839 T1521 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 264839 T1551 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 264841 T1521 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 264842 T1521 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 264843 T1521
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 264845 T1550 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 264848 T1521 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3
[junit4] 2> 264848 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 264852 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 264852 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 264854 T1555 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@75081c29
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264854 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 264854 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 264855 T1521
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 264857 T1521 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 264857 T1521 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 264858 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 264858 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 264859 T1557 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@3ef0d409
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 264859 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 264860 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 264861 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 264863 T1550 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: collection1
[junit4] 2> 264864 T1550
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
collection1 with shards [shard1, shard2]
[junit4] 2> 264864 T1550
oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2
[junit4] 2> 264866 T1550 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to create_collection /collections/collection1/state.json
[junit4] 2> 264867 T1558 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> 264867 T1559 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> 265951 T1521 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 265951 T1521 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 265955 T1521 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 265957 T1521 oejs.AbstractConnector.doStart Started
[email protected]:44036
[junit4] 2> 265959 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 265959 T1521 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 265960 T1521 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003
[junit4] 2> 265960 T1521 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/'
[junit4] 2> 265989 T1521 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solr.xml
[junit4] 2> 266042 T1521 oasc.CoreContainer.<init> New CoreContainer
607609565
[junit4] 2> 266042 T1521 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/]
[junit4] 2> 266043 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 266043 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 266044 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 266044 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 266044 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 266044 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 266045 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 266045 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 266045 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 266047 T1521 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 266049 T1521 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 266049 T1521 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 266050 T1521 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 266050 T1521 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:44019/solr
[junit4] 2> 266050 T1521 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 266050 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 266051 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 266053 T1570 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2f64acdf
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 266053 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 266054 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 266055 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 266056 T1572 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4f4de23 name:ZooKeeperConnection
Watcher:127.0.0.1:44019/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 266056 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 266061 T1521
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 267065 T1521 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44036_
[junit4] 2> 267066 T1521 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44036_
[junit4] 2> 267069 T1521 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 267074 T1521 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3
[junit4] 2> 267074 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 268179 T1521 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 268180 T1521 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 268185 T1521 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 268186 T1521 oejs.AbstractConnector.doStart Started
[email protected]:44039
[junit4] 2> 268188 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 268189 T1521 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 268189 T1521 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-004
[junit4] 2> 268189 T1521 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-004/'
[junit4] 2> 268218 T1521 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-004/solr.xml
[junit4] 2> 268270 T1521 oasc.CoreContainer.<init> New CoreContainer
1165823617
[junit4] 2> 268271 T1521 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-004/]
[junit4] 2> 268272 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 268272 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 268272 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 268272 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 268273 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 268273 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 268273 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 268273 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 268274 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 268276 T1521 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 268278 T1521 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 268278 T1521 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 268278 T1521 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 268278 T1521 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:44019/solr
[junit4] 2> 268279 T1521 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 268279 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 268280 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 268281 T1585 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@5e9601b5
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 268282 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 268283 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 268284 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 268285 T1587 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@78a22a38
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 268285 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 268290 T1521
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 269294 T1521 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44039_
[junit4] 2> 269295 T1521 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44039_
[junit4] 2> 269298 T1521 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 269303 T1521 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3
[junit4] 2> 269303 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 270690 T1521 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 270692 T1521 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 270696 T1521 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 270697 T1521 oejs.AbstractConnector.doStart Started
[email protected]:44042
[junit4] 2> 270699 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 270700 T1521 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 270700 T1521 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005
[junit4] 2> 270700 T1521 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/'
[junit4] 2> 270729 T1521 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/solr.xml
[junit4] 2> 270781 T1521 oasc.CoreContainer.<init> New CoreContainer
923601810
[junit4] 2> 270781 T1521 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/]
[junit4] 2> 270782 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 270782 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 270783 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 270783 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 270783 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 270783 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 270784 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 270784 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 270784 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 270786 T1521 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 270788 T1521 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 270788 T1521 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 270788 T1521 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 270789 T1521 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:44019/solr
[junit4] 2> 270789 T1521 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 270789 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 270790 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 270792 T1600 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@642b8e34
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270792 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 270793 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 270795 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 270796 T1602 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6de956d3
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270796 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 270800 T1521
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 271804 T1521 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44042_
[junit4] 2> 271805 T1521 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44042_
[junit4] 2> 271809 T1521 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 272283 T1521 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3
[junit4] 2> 272283 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 273488 T1521 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 4
[junit4] 2> 273489 T1521 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 273493 T1521 oejus.SslContextFactory.doStart Enabled Protocols
[SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1,
TLSv1.1, TLSv1.2]
[junit4] 2> 273495 T1521 oejs.AbstractConnector.doStart Started
[email protected]:44045
[junit4] 2> 273497 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 273497 T1521 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 273497 T1521 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006
[junit4] 2> 273498 T1521 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/'
[junit4] 2> 273526 T1521 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solr.xml
[junit4] 2> 273578 T1521 oasc.CoreContainer.<init> New CoreContainer
662188509
[junit4] 2> 273578 T1521 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/]
[junit4] 2> 273579 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 120000
[junit4] 2> 273580 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to: null
[junit4] 2> 273580 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 273580 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 273580 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 273581 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 273581 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 273581 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 273581 T1521 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 273584 T1521 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=3400000&connTimeout=45000&retry=false
[junit4] 2> 273585 T1521 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 273586 T1521 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 273586 T1521 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 273586 T1521 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:44019/solr
[junit4] 2> 273586 T1521 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 273587 T1521
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 273587 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 273589 T1615 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4b027c50
name:ZooKeeperConnection Watcher:127.0.0.1:44019 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 273590 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 273590 T1521 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 273592 T1521 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 273593 T1617 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@295d66e0
name:ZooKeeperConnection Watcher:127.0.0.1:44019/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 273593 T1521 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 273598 T1521
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 274602 T1521 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44045_
[junit4] 2> 274603 T1521 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:44045_
[junit4] 2> 274606 T1521 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 274611 T1521 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3
[junit4] 2> 274611 T1521 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 274752 T1562 oasha.CollectionsHandler.handleCreateAction
Creating Collection :
collection.configName=conf1&router.field=myOwnField&version=2&action=CREATE&name=solrj_collection&replicationFactor=2&numShards=2&wt=javabin
[junit4] 2> 274754 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 274755 T1551 oasc.OverseerCollectionProcessor.run Overseer
Collection Processor: Get the message
id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 274756 T1621 oasc.OverseerCollectionProcessor.processMessage
WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 274757 T1621 oasc.OverseerCollectionProcessor.createConfNode
creating collections conf node /collections/solrj_collection
[junit4] 2> 274758 T1621 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection
[junit4] 2> 274760 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 274763 T1550 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: solrj_collection
[junit4] 2> 274763 T1550
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
solrj_collection with shards [shard1, shard2]
[junit4] 2> 274764 T1550
oasc.Overseer$ClusterStateUpdater.createCollection state version
solrj_collection 1
[junit4] 2> 274766 T1603 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 274766 T1618 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 274766 T1558 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 274766 T1588 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 274766 T1559 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 274766 T1573 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 274861 T1621 oasc.OverseerCollectionProcessor.createCollection
Creating SolrCores for new collection solrj_collection, shardNames [shard1,
shard2] , replicationFactor : 2
[junit4] 2> 274862 T1621 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:44036_
[junit4] 2> 274863 T1621 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:44045_
[junit4] 2> 274865 T1621 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:44031_
[junit4] 2> 274868 T1621 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:44042_
[junit4] 2> 274997 T1564 oasha.CoreAdminHandler.handleCreateAction core
create command
collection.configName=conf1&collection=solrj_collection&version=2&shard=shard1&action=CREATE&name=solrj_collection_shard1_replica1&qt=/admin/cores&numShards=2&wt=javabin
[junit4] 2> 274997 T1543 oasha.CoreAdminHandler.handleCreateAction core
create command
collection.configName=conf1&collection=solrj_collection&version=2&shard=shard2&action=CREATE&name=solrj_collection_shard2_replica1&qt=/admin/cores&numShards=2&wt=javabin
[junit4] 2> 274997 T1607 oasha.CoreAdminHandler.handleCreateAction core
create command
collection.configName=conf1&collection=solrj_collection&version=2&shard=shard1&action=CREATE&name=solrj_collection_shard1_replica2&qt=/admin/cores&numShards=2&wt=javabin
[junit4] 2> 275000 T1564 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica1 state=down collection=solrj_collection
[junit4] 2> 275001 T1543 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica1 state=down collection=solrj_collection
[junit4] 2> 275001 T1592 oasha.CoreAdminHandler.handleCreateAction core
create command
collection.configName=conf1&collection=solrj_collection&version=2&shard=shard2&action=CREATE&name=solrj_collection_shard2_replica2&qt=/admin/cores&numShards=2&wt=javabin
[junit4] 2> 275002 T1564 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 275002 T1543 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 275002 T1607 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica2 state=down collection=solrj_collection
[junit4] 2> 275002 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 275004 T1607 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 275004 T1592 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica2 state=down collection=solrj_collection
[junit4] 2> 275006 T1592 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 275007 T1550 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44036_",
[junit4] 2> "base_url":"https://127.0.0.1:44036";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "core":"solrj_collection_shard1_replica1"}
[junit4] 2> 275009 T1550 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44031_",
[junit4] 2> "base_url":"https://127.0.0.1:44031";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "core":"solrj_collection_shard2_replica1"}
[junit4] 2> 275011 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 275011 T1550 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44045_",
[junit4] 2> "base_url":"https://127.0.0.1:44045";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "core":"solrj_collection_shard1_replica2"}
[junit4] 2> 275014 T1550 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44042_",
[junit4] 2> "base_url":"https://127.0.0.1:44042";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"2",
[junit4] 2> "core":"solrj_collection_shard2_replica2"}
[junit4] 2> 275016 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 275017 T1558 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 275017 T1588 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 275017 T1573 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 275017 T1618 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 275017 T1559 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 275017 T1603 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276003 T1543 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 276003 T1564 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 276004 T1543 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 276004 T1564 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 276005 T1543 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 276005 T1564 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 276005 T1607 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 276006 T1564 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 276006 T1543 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 276006 T1592 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 276008 T1543 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 276006 T1607 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 276008 T1543 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/'
[junit4] 2> 276008 T1592 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 276007 T1564 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 276009 T1592 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 276009 T1607 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 276010 T1592 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 276010 T1564 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/'
[junit4] 2> 276010 T1607 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 276011 T1592 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 276012 T1592 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/solrj_collection_shard2_replica2/'
[junit4] 2> 276012 T1607 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 276013 T1607 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/'
[junit4] 2> 276079 T1543 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 276084 T1564 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 276087 T1592 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 276093 T1607 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 276141 T1564 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 276142 T1592 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 276148 T1607 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 276149 T1543 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 276150 T1543 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 276163 T1543 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 276250 T1564 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 276250 T1607 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 276251 T1592 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 276264 T1564 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 276265 T1607 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 276264 T1592 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 276583 T1543 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 276597 T1543 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 276600 T1543 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 276618 T1543 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276624 T1543 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276628 T1543 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276629 T1543
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276631 T1543
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276632 T1543 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276633 T1543
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276633 T1543
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276634 T1543 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica1' using configuration from collection
solrj_collection
[junit4] 2> 276634 T1543 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 276635 T1543 oasc.SolrCore.<init>
[solrj_collection_shard2_replica1] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/data/
[junit4] 2> 276635 T1543 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@39e3326
[junit4] 2> 276637 T1543 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/data
[junit4] 2> 276637 T1543 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 276638 T1543 oasc.SolrCore.initIndex WARN
[solrj_collection_shard2_replica1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 276638 T1543 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-002/solrj_collection_shard2_replica1/data/index
[junit4] 2> 276639 T1543 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=5,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 276640 T1543 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-6a1b2920)),segFN=segments_1,generation=1}
[junit4] 2> 276640 T1543 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 276647 T1543 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 276647 T1543 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 276648 T1543 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 276648 T1543 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 276649 T1543 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 276649 T1543 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 276650 T1543 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 276650 T1543 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 276651 T1543 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 276652 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 276652 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 276653 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 276654 T1543 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 276654 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 276655 T1543 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 276655 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 276656 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 276657 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 276657 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 276658 T1543 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 276682 T1543 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 276685 T1543 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 276687 T1543 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 276697 T1543 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 276700 T1607 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 276701 T1592 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 276704 T1543 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 276704 T1543 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 276705 T1543 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=29, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=57.205078125,
floorSegmentMB=2.099609375, forceMergeDeletesPctAllowed=27.785664596492587,
segmentsPerTier=13.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5590083243801204
[junit4] 2> 276706 T1564 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 276707 T1543 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-6a1b2920)),segFN=segments_1,generation=1}
[junit4] 2> 276707 T1543 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 276708 T1543 oass.SolrIndexSearcher.<init> Opening
Searcher@1b7047c2[solrj_collection_shard2_replica1] main
[junit4] 2> 276709 T1543 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 276710 T1543 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 276710 T1543 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 276710 T1607 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 276711 T1543
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 276711 T1592 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 276711 T1543 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 276712 T1543 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 276713 T1543
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 276713 T1543 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 276714 T1607 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 276714 T1543 oascc.SolrZkClient.makePath makePath:
/configs/conf1/_rest_managed.json
[junit4] 2> 276715 T1564 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 276715 T1592 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 276716 T1543
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new
znode /configs/conf1/_rest_managed.json
[junit4] 2> 276717 T1543 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 276717 T1543 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 276718 T1564 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 276722 T1630 oasc.SolrCore.registerSearcher
[solrj_collection_shard2_replica1] Registered new searcher
Searcher@1b7047c2[solrj_collection_shard2_replica1]
main{UninvertingDirectoryReader()}
[junit4] 2> 276726 T1543 oasc.CoreContainer.registerCore registering core:
solrj_collection_shard2_replica1
[junit4] 2> 276727 T1543 oasc.ZkController.register Register replica -
core:solrj_collection_shard2_replica1 address:https://127.0.0.1:44031
collection:solrj_collection shard:shard2
[junit4] 2> 276728 T1543 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection/leader_elect/shard2/election
[junit4] 2> 276733 T1543 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 276734 T1607 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276735 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 276736 T1543
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Waiting until we see
more replicas up for shard shard2: total=2 found=1 timeoutin=5.7512275E15ms
[junit4] 2> 276735 T1592 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276736 T1543
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Was waiting for
replicas to come up, but they are taking too long - assuming they won't come
back till later
[junit4] 2> 276737 T1543 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C69 name=solrj_collection_shard2_replica1
org.apache.solr.core.SolrCore@7b159982
url=https://127.0.0.1:44031/solrj_collection_shard2_replica1
node=127.0.0.1:44031_ C69_STATE=coll:solrj_collection
core:solrj_collection_shard2_replica1 props:{node_name=127.0.0.1:44031_,
base_url=https://127.0.0.1:44031, state=down,
core=solrj_collection_shard2_replica1}
[junit4] 2> 276738 T1543 C69 P44031 oasc.SyncStrategy.sync Sync replicas
to https://127.0.0.1:44031/solrj_collection_shard2_replica1/
[junit4] 2> 276738 T1543 C69 P44031 oasu.PeerSync.sync PeerSync:
core=solrj_collection_shard2_replica1 url=https://127.0.0.1:44031 START
replicas=[https://127.0.0.1:44042/solrj_collection_shard2_replica2/]
nUpdates=100
[junit4] 2> 276739 T1564 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276739 T1607 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276739 T1543 C69 P44031 oasu.PeerSync.sync PeerSync:
core=solrj_collection_shard2_replica1 url=https://127.0.0.1:44031 DONE. We
have no versions. sync failed.
[junit4] 2> 276742 T1543 C69 P44031 oasc.SyncStrategy.syncReplicas
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 276743 T1618 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276743 T1543 oasc.ShardLeaderElectionContext.runLeaderProcess
We failed sync, but we have no versions - we can't sync in that case - we were
active before, so become leader anyway
[junit4] 2> 276743 T1588 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276744 T1592 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276743 T1559 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276743 T1603 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276743 T1573 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276743 T1558 oascc.ZkStateReader$2.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 276744 T1543 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: https://127.0.0.1:44031/solrj_collection_shard2_replica1/
shard2
[junit4] 2> 276747 T1543 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection/leaders/shard2
[junit4] 2> 276747 T1607 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276747 T1564 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 276749 T1607
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276749 T1607
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276750 T1607 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276750 T1592 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276751 T1607
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276751 T1607
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276751 T1592
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276752 T1607 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica2' using configuration from collection
solrj_collection
[junit4] 2> 276752 T1558 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 276753 T1607 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 276752 T1592
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276754 T1607 oasc.SolrCore.<init>
[solrj_collection_shard1_replica2] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/data/
[junit4] 2> 276753 T1564 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276754 T1607 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@39e3326
[junit4] 2> 276754 T1592 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276755 T1564
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276756 T1564
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276756 T1607 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/data
[junit4] 2> 276757 T1564 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 276757 T1592
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276757 T1607 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/data/index/
[junit4] 2> 276758 T1564
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 276758 T1592
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276759 T1564
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 276758 T1607 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica2] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/data/index'
doesn't exist. Creating new index...
[junit4] 2> 276760 T1564 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica1' using configuration from collection
solrj_collection
[junit4] 2> 276759 T1592 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica2' using configuration from collection
solrj_collection
[junit4] 2> 276761 T1564 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 276761 T1607 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/solrj_collection_shard1_replica2/data/index
[junit4] 2> 276762 T1564 oasc.SolrCore.<init>
[solrj_collection_shard1_replica1] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/data/
[junit4] 2> 276761 T1592 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 276763 T1564 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@39e3326
[junit4] 2> 276762 T1607 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=5,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 276763 T1592 oasc.SolrCore.<init>
[solrj_collection_shard2_replica2] Opening new SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/solrj_collection_shard2_replica2/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/solrj_collection_shard2_replica2/data/
[junit4] 2> 276764 T1564 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/data
[junit4] 2> 276765 T1607 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-5b478b91)),segFN=segments_1,generation=1}
[junit4] 2> 276765 T1564 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/data/index/
[junit4] 2> 276764 T1592 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@39e3326
[junit4] 2> 276766 T1564 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica1] Solr index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 276765 T1607 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 276767 T1592 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-005/solrj_collection_shard2_replica2/data
[junit4] 2> 276767 T1564 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-003/solrj_collection_shard1_replica1/data/index
[junit4] 2> 276767 T1592 oasc.SolrCore.getNewInd
[...truncated too long message...]
[junit4] 2> 1002044 T1521 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1002044 T1521 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/awholynewcollection_2_shard1_replica1/data/index
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/awholynewcollection_2_shard1_replica1/data/index;done=false>>]
[junit4] 2> 1002045 T1521 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/awholynewcollection_2_shard1_replica1/data/index
[junit4] 2> 1002045 T1521 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/awholynewcollection_2_shard1_replica1/data
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/awholynewcollection_2_shard1_replica1/data;done=false>>]
[junit4] 2> 1002046 T1521 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001/tempDir-006/awholynewcollection_2_shard1_replica1/data
[junit4] 2> 1002046 T2948 oascc.ZkStateReader.updateWatchedCollection
Updating data for awholynewcollection_2 to ver 23
[junit4] 2> 1002046 T2948 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "collection":"awholynewcollection_2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44045_",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "base_url":"https://127.0.0.1:44045";,
[junit4] 2> "state":"down",
[junit4] 2> "numShards":"4",
[junit4] 2> "core":"awholynewcollection_2_shard1_replica1"}
[junit4] 2> 1002049 T2935 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 1002049 T2948 oasc.Overseer$ClusterStateUpdater.updateZkStates
going to update_collection /collections/awholynewcollection_2/state.json
[junit4] 2> 1002050 T2935 oascc.ZkStateReader$7.process A cluster state
change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/awholynewcollection_2/state.json, has occurred - updating...
[junit4] 2> 1002052 T2935 oascc.ZkStateReader.updateWatchedCollection
Updating data for awholynewcollection_2 to ver 24
[junit4] 2> 1002055 T1521 oasc.Overseer.close Overseer
(id=92469363890192400-127.0.0.1:44045_-n_0000000004) closing
[junit4] 2> 1002056 T2948 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:44045_
[junit4] 2> 1002058 T2951
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read
the data org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[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.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:347)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:344)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:344)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:346)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:89)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:296)
[junit4] 2>
[junit4] 2> 1003558 T2935 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1003559 T1521 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1005699 T1521 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 1005703 T1521 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:44019 44019
[junit4] 2> 1005787 T1973 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:44019 44019
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch
-Dtests.seed=880535A34215B5C0 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=sr_RS -Dtests.timezone=Europe/Vaduz -Dtests.file.encoding=UTF-8
[junit4] ERROR 743s J3 |
CollectionsAPIDistributedZkTest.testDistribSearch <<<
[junit4] > Throwable #1:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error
CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core
[halfcollection_shard1_replica1] Caused by: Could not get shard id for core:
halfcollection_shard1_replica1
[junit4] > at
__randomizedtesting.SeedInfo.seed([880535A34215B5C0:9E3BBBB354AD5FC]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:550)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
[junit4] > at
org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:583)
[junit4] > at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:205)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1005804 T1521 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J3/temp/solr.cloud.CollectionsAPIDistributedZkTest-880535A34215B5C0-001
[junit4] 2> 742886 T1520 ccr.ThreadLeakControl.checkThreadLeaks WARNING
Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=sr_RS,
timezone=Europe/Vaduz
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=16,threads=1,free=32759520,total=355467264
[junit4] 2> NOTE: All tests run in this JVM:
[HdfsChaosMonkeySafeLeaderTest, DocValuesMultiTest,
TestSchemaSimilarityResource, TestRecoveryHdfs, SolrTestCaseJ4Test,
CollectionsAPIAsyncDistributedZkTest, TestImplicitCoreProperties,
OutputWriterTest, FieldAnalysisRequestHandlerTest, TestFastLRUCache,
SOLR749Test, DeleteLastCustomShardedReplicaTest,
TestOverriddenPrefixQueryForCustomFieldType, TestManagedResource,
TestHighFrequencyDictionaryFactory, SharedFSAutoReplicaFailoverUtilsTest,
ShardRoutingTest, UUIDFieldTest, TestFieldResource,
FieldMutatingUpdateProcessorTest, StandardRequestHandlerTest,
SolrPluginUtilsTest, BadIndexSchemaTest, TestDocSet, TestPhraseSuggestions,
SolrXmlInZkTest, TestCloudInspectUtil, CollectionsAPIDistributedZkTest]
[junit4] Completed on J3 in 743.94s, 1 test, 1 error <<< FAILURES!
[...truncated 784 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:491:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:471:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:60:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build.xml:189:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/common-build.xml:496:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:1358:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:961:
There were test failures: 429 suites, 1772 tests, 1 error, 59 ignored (24
assumptions)
Total time: 58 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-trunk-Java7 #4851
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 147291094 bytes
Compression is 0.0%
Took 27 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
