Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10905/
Java: 64bit/jdk1.7.0_65 -XX:+UseCompressedOops -XX:+UseG1GC
4 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest
Error Message:
ERROR: SolrZkClient opens=16 closes=15
Stack Trace:
java.lang.AssertionError: ERROR: SolrZkClient opens=16 closes=15
at __randomizedtesting.SeedInfo.seed([EC56B329DF05FAC4]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.SolrTestCaseJ4.endTrackingZkClients(SolrTestCaseJ4.java:451)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:184)
at sun.reflect.GeneratedMethodAccessor29.invoke(Unknown Source)
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$5.evaluate(RandomizedRunner.java:790)
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)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest: 1) Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at
java.lang.Thread.sleep(Native Method) at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
2) Thread[id=1694,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-EventThread,
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at
sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:
1) Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at java.lang.Thread.sleep(Native Method)
at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
2) Thread[id=1694,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-EventThread,
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
at __randomizedtesting.SeedInfo.seed([EC56B329DF05FAC4]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest] at
java.lang.Thread.sleep(Native Method) at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
at __randomizedtesting.SeedInfo.seed([EC56B329DF05FAC4]:0)
REGRESSION:
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
createcollection the collection time out:180s
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException:
createcollection the collection time out:180s
at
org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:551)
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.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:884)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:203)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
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 11446 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/init-core-data-001
[junit4] 2> 805339 T1539 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (true)
[junit4] 2> 805339 T1539 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /_ips/i
[junit4] 2> 805343 T1539 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 805343 T1539 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 805344 T1540 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 805444 T1539 oasc.ZkTestServer.run start zk server on
port:47930
[junit4] 2> 805445 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 805447 T1546 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7270acca
name:ZooKeeperConnection Watcher:127.0.0.1:47930 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 805447 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 805447 T1539 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 805450 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 805451 T1548 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6931e342
name:ZooKeeperConnection Watcher:127.0.0.1:47930/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 805451 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 805451 T1539 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 805453 T1539 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 805455 T1539 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 805456 T1539 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 805457 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 805458 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 805460 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 805460 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 805462 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 805462 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 805464 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 805464 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 805466 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 805466 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 805467 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 805468 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 805469 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 805469 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 805471 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 805471 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 805473 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 805473 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 805474 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 805475 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 805476 T1539 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 805476 T1539 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> Using new style solr.xml
[junit4] 2> 805747 T1539 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 805750 T1539 oejs.AbstractConnector.doStart Started
[email protected]:38920
[junit4] 2> 805750 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 805750 T1539 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 805751 T1539 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002
[junit4] 2> 805751 T1539 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002/'
[junit4] 2> 805762 T1539 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002/solr.xml
[junit4] 2> 805780 T1539 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002
[junit4] 2> 805780 T1539 oasc.CoreContainer.<init> New CoreContainer
1398554708
[junit4] 2> 805780 T1539 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002/]
[junit4] 2> 805781 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 805781 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 805781 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 805782 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 805782 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 805782 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 805782 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 805782 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 805783 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 805783 T1539 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 805783 T1539 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 805783 T1539 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 805784 T1539 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 805784 T1539 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:47930/solr
[junit4] 2> 805784 T1539 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 805785 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 805786 T1559 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4d83758d
name:ZooKeeperConnection Watcher:127.0.0.1:47930 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 805786 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 805788 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 805789 T1561 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@48b185b8
name:ZooKeeperConnection Watcher:127.0.0.1:47930/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 805789 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 805790 T1539 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 805792 T1539 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 805794 T1539 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 805796 T1539 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 805798 T1539 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 805800 T1539 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 805801 T1539 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38920__ips%2Fi
[junit4] 2> 805802 T1539 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:38920__ips%2Fi
[junit4] 2> 805803 T1539 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 805805 T1539 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 805807 T1539 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:38920__ips%2Fi
[junit4] 2> 805807 T1539 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 805809 T1539 oasc.Overseer.start Overseer
(id=92174574699282435-127.0.0.1:38920__ips%2Fi-n_0000000000) starting
[junit4] 2> 805810 T1539 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 805816 T1563 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 805816 T1539 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 805818 T1539 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 805819 T1539
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 805821 T1562 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 805822 T1539 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002
[junit4] 2> 805827 T1539 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002/conf/
[junit4] 2> 805943 T1539 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 805944 T1539 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 805944 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 805945 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 805946 T1566 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@22c9bbdf
name:ZooKeeperConnection Watcher:127.0.0.1:47930/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 805946 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 805947 T1539
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 805949 T1539 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 806225 T1539 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 806226 T1539 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 806229 T1539 oejs.AbstractConnector.doStart Started
[email protected]:51994
[junit4] 2> 806229 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 806230 T1539 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 806230 T1539 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003
[junit4] 2> 806230 T1539 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/'
[junit4] 2> 806241 T1539 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solr.xml
[junit4] 2> 806259 T1539 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003
[junit4] 2> 806259 T1539 oasc.CoreContainer.<init> New CoreContainer
1725335740
[junit4] 2> 806259 T1539 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/]
[junit4] 2> 806260 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 806260 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 806260 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 806261 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 806261 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 806261 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 806261 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 806262 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 806262 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 806262 T1539 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 806262 T1539 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 806263 T1539 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 806263 T1539 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 806263 T1539 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:47930/solr
[junit4] 2> 806263 T1539 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 806264 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 806265 T1577 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@eab2363 name:ZooKeeperConnection
Watcher:127.0.0.1:47930 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 806266 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 806268 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 806269 T1579 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@253949ff
name:ZooKeeperConnection Watcher:127.0.0.1:47930/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 806269 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 806273 T1539
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 807276 T1539 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51994__ips%2Fi
[junit4] 2> 807277 T1539 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:51994__ips%2Fi
[junit4] 2> 807283 T1539 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003
[junit4] 2> 807288 T1539 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/conf/
[junit4] 2> 807403 T1539 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 807404 T1539 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 807404 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 807673 T1539 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 807674 T1539 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 807676 T1539 oejs.AbstractConnector.doStart Started
[email protected]:40848
[junit4] 2> 807676 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 807677 T1539 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 807677 T1539 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004
[junit4] 2> 807677 T1539 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004/'
[junit4] 2> 807688 T1539 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004/solr.xml
[junit4] 2> 807712 T1539 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004
[junit4] 2> 807713 T1539 oasc.CoreContainer.<init> New CoreContainer
1046144496
[junit4] 2> 807713 T1539 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004/]
[junit4] 2> 807714 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 807714 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 807714 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 807715 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 807715 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 807715 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 807715 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 807715 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 807716 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 807716 T1539 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 807716 T1539 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 807717 T1539 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 807717 T1539 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 807717 T1539 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:47930/solr
[junit4] 2> 807717 T1539 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 807718 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 807719 T1591 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@41ae0f8b
name:ZooKeeperConnection Watcher:127.0.0.1:47930 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 807719 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 807721 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 807722 T1593 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@44f75184
name:ZooKeeperConnection Watcher:127.0.0.1:47930/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 807723 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 807727 T1539
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 808730 T1539 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40848__ips%2Fi
[junit4] 2> 808731 T1539 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:40848__ips%2Fi
[junit4] 2> 808737 T1539 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004
[junit4] 2> 808744 T1539 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-004/conf/
[junit4] 2> 808908 T1539 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 808909 T1539 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 808909 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 809188 T1539 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 3
[junit4] 2> 809189 T1539 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 809191 T1539 oejs.AbstractConnector.doStart Started
[email protected]:33302
[junit4] 2> 809192 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 809192 T1539 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 809193 T1539 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005
[junit4] 2> 809193 T1539 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/'
[junit4] 2> 809204 T1539 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solr.xml
[junit4] 2> 809221 T1539 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005
[junit4] 2> 809221 T1539 oasc.CoreContainer.<init> New CoreContainer
993889123
[junit4] 2> 809221 T1539 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/]
[junit4] 2> 809222 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 809222 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 809222 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 809223 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 809223 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 809223 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 809223 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 809223 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 809224 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 809224 T1539 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 809224 T1539 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 809224 T1539 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 809225 T1539 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 809225 T1539 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:47930/solr
[junit4] 2> 809225 T1539 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 809226 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 809227 T1605 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@458a2ef7
name:ZooKeeperConnection Watcher:127.0.0.1:47930 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 809227 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 809229 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 809230 T1607 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@ba63adf name:ZooKeeperConnection
Watcher:127.0.0.1:47930/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 809230 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 809235 T1539
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 810238 T1539 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33302__ips%2Fi
[junit4] 2> 810239 T1539 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:33302__ips%2Fi
[junit4] 2> 810247 T1539 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005
[junit4] 2> 810256 T1539 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/conf/
[junit4] 2> 810415 T1539 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 810417 T1539 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 810417 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 810831 T1539 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 4
[junit4] 2> 810831 T1539 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 810846 T1539 oejs.AbstractConnector.doStart Started
[email protected]:43512
[junit4] 2> 810848 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 810849 T1539 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 810849 T1539 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006
[junit4] 2> 810849 T1539 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/'
[junit4] 2> 810861 T1539 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solr.xml
[junit4] 2> 810965 T1539 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006
[junit4] 2> 810965 T1539 oasc.CoreContainer.<init> New CoreContainer
1774916315
[junit4] 2> 810965 T1539 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/]
[junit4] 2> 810966 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 810966 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 810967 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 810967 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 810967 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 810967 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 810968 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 810968 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 810968 T1539 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 810968 T1539 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 810969 T1539 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 810969 T1539 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 810969 T1539 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 810969 T1539 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:47930/solr
[junit4] 2> 810970 T1539 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 810970 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 810972 T1619 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@13431e20
name:ZooKeeperConnection Watcher:127.0.0.1:47930 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 810972 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 810975 T1539 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 810975 T1621 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7dffb08 name:ZooKeeperConnection
Watcher:127.0.0.1:47930/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 810975 T1539 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 810980 T1539
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 811982 T1539 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43512__ips%2Fi
[junit4] 2> 811983 T1539 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:43512__ips%2Fi
[junit4] 2> 811989 T1539 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006
[junit4] 2> 811996 T1539 oasc.CorePropertiesLocator.discoverUnder Found
core conf in
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/conf/
[junit4] 2> 812151 T1539 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 812152 T1539 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 812152 T1539 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 812158 T1572 oasha.CollectionsHandler.handleCreateAction
Creating Collection :
replicationFactor=2&action=CREATE&collection.configName=conf1&version=2&wt=javabin&numShards=2&router.field=myOwnField&router.name=compositeId&name=solrj_collection
[junit4] 2> 812159 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812159 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812159 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812160 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812160 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812160 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812160 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/collection-queue-work state:
SyncConnected type NodeChildrenChanged
[junit4] 2> 812161 T1563 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> "router.name":"compositeId"}
[junit4] 2> 812161 T1623 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> "router.name":"compositeId"}
[junit4] 2> 812162 T1623 oasc.OverseerCollectionProcessor.createConfNode
creating collections conf node /collections/solrj_collection
[junit4] 2> 812162 T1623 oascc.SolrZkClient.makePath makePath:
/collections/solrj_collection
[junit4] 2> 812165 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812166 T1562 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 812167 T1562 oasc.Overseer$ClusterStateUpdater.buildCollection
building a new collection: solrj_collection
[junit4] 2> 812167 T1562
oasc.Overseer$ClusterStateUpdater.createCollection Create collection
solrj_collection with shards [shard1, shard2]
[junit4] 2> 812168 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812169 T1593 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> 812169 T1607 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> 812169 T1579 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> 812169 T1621 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> 812169 T1566 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> 812169 T1561 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> 812265 T1623 oasc.OverseerCollectionProcessor.createCollection
Creating SolrCores for new collection solrj_collection, shardNames [shard1,
shard2] , replicationFactor : 2
[junit4] 2> 812265 T1623 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:38920__ips%2Fi
[junit4] 2> 812266 T1623 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of
collection solrj_collection on 127.0.0.1:33302__ips%2Fi
[junit4] 2> 812268 T1623 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:43512__ips%2Fi
[junit4] 2> 812269 T1557 oasha.CoreAdminHandler.handleCreateAction core
create command
action=CREATE&qt=/admin/cores&shard=shard1&collection.configName=conf1&version=2&collection=solrj_collection&wt=javabin&numShards=2&name=solrj_collection_shard1_replica1
[junit4] 2> 812269 T1623 oasc.OverseerCollectionProcessor.createCollection
Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of
collection solrj_collection on 127.0.0.1:51994__ips%2Fi
[junit4] 2> 812269 T1601 oasha.CoreAdminHandler.handleCreateAction core
create command
action=CREATE&qt=/admin/cores&shard=shard1&collection.configName=conf1&version=2&collection=solrj_collection&wt=javabin&numShards=2&name=solrj_collection_shard1_replica2
[junit4] 2> 812270 T1614 oasha.CoreAdminHandler.handleCreateAction core
create command
action=CREATE&qt=/admin/cores&shard=shard2&collection.configName=conf1&version=2&collection=solrj_collection&wt=javabin&numShards=2&name=solrj_collection_shard2_replica1
[junit4] 2> 812271 T1574 oasha.CoreAdminHandler.handleCreateAction core
create command
action=CREATE&qt=/admin/cores&shard=shard2&collection.configName=conf1&version=2&collection=solrj_collection&wt=javabin&numShards=2&name=solrj_collection_shard2_replica2
[junit4] 2> 812271 T1557 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica1 state=down collection=solrj_collection
[junit4] 2> 812271 T1601 oasc.ZkController.publish publishing
core=solrj_collection_shard1_replica2 state=down collection=solrj_collection
[junit4] 2> 812272 T1614 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica1 state=down collection=solrj_collection
[junit4] 2> 812272 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812272 T1557 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 812272 T1601 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 812273 T1574 oasc.ZkController.publish publishing
core=solrj_collection_shard2_replica2 state=down collection=solrj_collection
[junit4] 2> 812273 T1614 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 812274 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812274 T1562 oascc.ZkStateReader.updateClusterState Updating
cloud state from ZooKeeper...
[junit4] 2> 812274 T1574 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 812275 T1562 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38920/_ips/i";,
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38920__ips%2Fi",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 812277 T1562 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33302/_ips/i";,
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33302__ips%2Fi",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 812279 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812280 T1562 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43512/_ips/i";,
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:43512__ips%2Fi",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 812281 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812282 T1562 oasc.Overseer$ClusterStateUpdater.updateState
Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51994/_ips/i";,
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51994__ips%2Fi",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 812285 T1561 oasc.DistributedQueue$LatchChildWatcher.process
LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type
NodeChildrenChanged
[junit4] 2> 812387 T1561 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> 812387 T1579 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> 812387 T1621 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> 812387 T1607 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> 812387 T1566 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> 812387 T1593 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> 813273 T1557 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 813273 T1601 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 813273 T1557 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 813273 T1601 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 813274 T1614 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 813274 T1614 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 813274 T1601 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 813274 T1601 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 813274 T1557 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 813275 T1574 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 813274 T1614 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 813275 T1614 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 813275 T1601 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 813275 T1574 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:solrj_collection
[junit4] 2> 813275 T1557 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 813276 T1614 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 813276 T1601 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/'
[junit4] 2> 813276 T1614 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/'
[junit4] 2> 813276 T1574 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 813277 T1574 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 813277 T1557 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 813278 T1557 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-002/solrj_collection_shard1_replica1/'
[junit4] 2> 813278 T1574 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 813278 T1574 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/'
[junit4] 2> 813315 T1601 oasc.SolrConfig.<init> Using Lucene MatchVersion:
LUCENE_5_0
[junit4] 2> 813315 T1574 oasc.SolrConfig.<init> Using Lucene MatchVersion:
LUCENE_5_0
[junit4] 2> 813320 T1614 oasc.SolrConfig.<init> Using Lucene MatchVersion:
LUCENE_5_0
[junit4] 2> 813328 T1557 oasc.SolrConfig.<init> Using Lucene MatchVersion:
LUCENE_5_0
[junit4] 2> 813339 T1601 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 813341 T1601 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 813343 T1574 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 813344 T1574 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 813347 T1601 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 813351 T1574 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 813357 T1614 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 813359 T1614 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 813364 T1557 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 813366 T1557 oass.IndexSchema.readSchema Reading Solr Schema
from schema.xml
[junit4] 2> 813368 T1614 oass.IndexSchema.readSchema
[solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 813394 T1557 oass.IndexSchema.readSchema
[solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 813635 T1601 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 813635 T1574 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 813640 T1601 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 813641 T1574 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 813642 T1601 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 813644 T1574 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 813655 T1614 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 813658 T1601 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 813659 T1574 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 813659 T1614 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 813661 T1601 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 813661 T1614 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 813662 T1574 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 813664 T1601 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 813664 T1601
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 813670 T1601
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 813670 T1601 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 813670 T1574 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 813671 T1601
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 813671 T1601
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 813671 T1574
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 813672 T1574
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 813672 T1601 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard1_replica2' using configuration from collection
solrj_collection
[junit4] 2> 813672 T1574 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 813672 T1601 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 813673 T1601 oasc.SolrCore.<init>
[solrj_collection_shard1_replica2] Opening new SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/data/
[junit4] 2> 813673 T1574
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 813673 T1574
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 813673 T1601 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@397ca96f
[junit4] 2> 813674 T1574 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica2' using configuration from collection
solrj_collection
[junit4] 2> 813674 T1574 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 813675 T1574 oasc.SolrCore.<init>
[solrj_collection_shard2_replica2] Opening new SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/data/
[junit4] 2> 813675 T1601 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/data
[junit4] 2> 813675 T1574 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@397ca96f
[junit4] 2> 813675 T1601 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/data/index/
[junit4] 2> 813676 T1601 oasc.SolrCore.initIndex WARN
[solrj_collection_shard1_replica2] Solr index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/data/index'
doesn't exist. Creating new index...
[junit4] 2> 813676 T1574 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/data
[junit4] 2> 813676 T1601 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-005/solrj_collection_shard1_replica2/data/index
[junit4] 2> 813676 T1574 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/data/index/
[junit4] 2> 813677 T1601 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 813677 T1614 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 813677 T1574 oasc.SolrCore.initIndex WARN
[solrj_collection_shard2_replica2] Solr index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/data/index'
doesn't exist. Creating new index...
[junit4] 2> 813678 T1601 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@27d8e42b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56f516ed),segFN=segments_1,generation=1}
[junit4] 2> 813678 T1574 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-003/solrj_collection_shard2_replica2/data/index
[junit4] 2> 813678 T1601 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 813679 T1574 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 813679 T1574 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@664743ea
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2d98004a),segFN=segments_1,generation=1}
[junit4] 2> 813680 T1574 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 813680 T1614 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 813683 T1601 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 813683 T1601 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 813683 T1574 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 813683 T1601 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 813683 T1614 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 813684 T1601 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 813684 T1574 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 813684 T1601 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 813685 T1614
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 813685 T1614
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 813685 T1601 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 813684 T1574 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 813686 T1601 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 813686 T1574 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 813685 T1614 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 813686 T1574 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 813686 T1601 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 813687 T1601 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 813686 T1574 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 813687 T1614
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 813687 T1614
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 813687 T1574 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 813687 T1601 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 813688 T1574 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 813688 T1601 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 813689 T1601 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 813688 T1614 oasc.CoreContainer.create Creating SolrCore
'solrj_collection_shard2_replica1' using configuration from collection
solrj_collection
[junit4] 2> 813689 T1601 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 813688 T1574 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 813689 T1601 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 813689 T1614 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 813690 T1557 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 813690 T1614 oasc.SolrCore.<init>
[solrj_collection_shard2_replica1] Opening new SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/data/
[junit4] 2> 813690 T1601 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 813690 T1574 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 813691 T1601 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 813691 T1574 oasc.RequestHandlers.initHandlersFromConfig
created /get: solr.RealTimeGetHandler
[junit4] 2> 813692 T1574 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 813691 T1614 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@397ca96f
[junit4] 2> 813692 T1574 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 813692 T1601 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 813692 T1574 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 813692 T1614 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/data
[junit4] 2> 813693 T1574 oasc.RequestHandlers.initHandlersFromConfig
created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 813693 T1614 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 813693 T1574 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 813693 T1614 oasc.SolrCore.initIndex WARN
[solrj_collection_shard2_replica1] Solr index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 813694 T1574 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 813694 T1614 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./temp/solr.cloud.CollectionsAPIDistributedZkTest-EC56B329DF05FAC4-001/tempDir-006/solrj_collection_shard2_replica1/data/index
[junit4] 2> 813694 T1614 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=true, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 813695 T1614 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@51b9036d
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77e85842),segFN=segments_1,generation=1}
[junit4] 2> 813695 T1614 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 813695 T1574 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 813695 T1601 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 813696 T1557 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 813697 T1601 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 813697 T1574 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 813698 T1601 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 813698 T1574 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 813699 T1601 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=79.880859375,
floorSegmentMB=1.4296875, forceMergeDeletesPctAllowed=20.012902288228894,
segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8644149566798714
[junit4] 2> 813699 T1574 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=79.880859375,
floorSegmentMB=1.4296875, forceMergeDeletesPctAllowed=20.012902288228894,
segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8644149566798714
[junit4] 2> 813699 T1601 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@27d8e42b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56f516ed),segFN=segments_1,generation=1}
[junit4] 2> 813699 T1601 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 813699 T1614 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 813699 T1557 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 813700 T1614 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 813700 T1601 oass.SolrIndexSearcher.<init> Opening
Searcher@669b9d0b[solrj_collection_shard1_replica2] main
[junit4] 2> 813699 T1574 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@664743ea
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2d98004a),segFN=segments_1,generation=1}
[junit4] 2> 813701 T1574 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 813701 T1601 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 813701 T1614 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 813701 T1574 oass.SolrIndexSearcher.<init> Opening
Searcher@5ec48466[solrj_collection_shard2_replica2] main
[junit4] 2> 813702 T1614 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 813702 T1601 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 813703 T1601 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 813702 T1574 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/solrj_collection
[junit4] 2> 813703 T1614 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 813703 T1601
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 813704 T1601 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 813704 T1614 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 813704 T1601 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 813704 T1574 oascc.ZkStateReader.readConfigName
path=/collections/solrj_collection configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 813705 T1574 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 813705 T1614 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 813706 T1574
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 813706 T1574 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 813706 T1614 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 813705 T1601
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 813707 T1614 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[...truncated too long message...]
keeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2>
[junit4] 2> 1107038 T1693 oaz.ClientCnxn$SendThread.run WARN Session
0x14778498e46000e for server null, unexpected error, closing socket connection
and attempting reconnect java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2>
[junit4] 2> 1107538 T1693 oaz.ClientCnxn$SendThread.run WARN Unexpected
exception java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
[junit4] 2>
[junit4] 2> 1108038 T1693 oazc.StaticHostProvider.next WARN Unexpected
exception java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2>
[junit4] 2> 1108039 T1693 oaz.ClientCnxn$SendThread.run WARN Session
0x14778498e46000e for server null, unexpected error, closing socket connection
and attempting reconnect java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2>
[junit4] 2> 1108538 T1693 oazc.StaticHostProvider.next WARN Unexpected
exception java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2>
[junit4] 2> 1108539 T1693 oaz.ClientCnxn$SendThread.run WARN Session
0x14778498e46000e for server null, unexpected error, closing socket connection
and attempting reconnect java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:739)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2>
[junit4] 2> 303700 T1538 ccr.ThreadLeakControl.tryToInterruptAll SEVERE
There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
[junit4] 2> NOTE: test params are: codec=Lucene45, sim=DefaultSimilarity,
locale=lv_LV, timezone=America/Mexico_City
[junit4] 2> NOTE: Linux 3.13.0-32-generic amd64/Oracle Corporation
1.7.0_65 (64-bit)/cpus=8,threads=2,free=321337816,total=478150656
[junit4] 2> NOTE: All tests run in this JVM: [SimplePostToolTest,
DocValuesTest, DistributedExpandComponentTest, TestIndexSearcher,
TestFiltering, TestAnalyzeInfixSuggestions, TestInfoStreamLogging,
IndexSchemaRuntimeFieldTest, DateMathParserTest, CSVRequestHandlerTest,
SampleTest, DistributedDebugComponentTest, DistributedIntervalFacetingTest,
TestRealTimeGet, TestChildDocTransformer, TestDistributedGrouping, SOLR749Test,
LoggingHandlerTest, AtomicUpdatesTest, TestMergePolicyConfig,
ResourceLoaderTest, TestIntervalFaceting, TestValueSourceCache,
CoreAdminRequestStatusTest, TestRandomFaceting, TestStressUserVersions,
SSLMigrationTest, TestLRUCache, TestPostingsSolrHighlighter,
TestSchemaSimilarityResource, AnalysisAfterCoreReloadTest,
TestDocBasedVersionConstraints, OverseerStatusTest, TestDynamicFieldResource,
TestNoOpRegenerator, FieldAnalysisRequestHandlerTest,
AbstractAnalyticsStatsTest, TestManagedStopFilterFactory, CircularListTest,
SystemInfoHandlerTest, AnalyticsMergeStrategyTest, SolrRequestParserTest,
HdfsCollectionsAPIDistributedZkTest, LegacyHTMLStripCharFilterTest,
TestFieldSortValues, SuggesterFSTTest, SolrCoreTest, SliceStateTest,
TestSuggestSpellingConverter, AbstractAnalyticsFacetTest, TestJmxMonitoredMap,
TestReplicationHandlerBackup, TestZkChroot, BinaryUpdateRequestHandlerTest,
TestLeaderElectionZkExpiry, ConvertedLegacyTest, BasicDistributedZk2Test,
ResponseHeaderTest, MultiTermTest, CopyFieldTest,
DistributedQueryComponentCustomSortTest, SolrInfoMBeanTest,
TestReloadAndDeleteDocs, CollectionsAPIDistributedZkTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionsAPIDistributedZkTest -Dtests.seed=EC56B329DF05FAC4
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lv_LV
-Dtests.timezone=America/Mexico_City -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | CollectionsAPIDistributedZkTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrZkClient
opens=16 closes=15
[junit4] > at
__randomizedtesting.SeedInfo.seed([EC56B329DF05FAC4]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.endTrackingZkClients(SolrTestCaseJ4.java:451)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:184)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.CollectionsAPIDistributedZkTest:
[junit4] > 1) Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] > 2) Thread[id=1694,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-EventThread,
state=WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
[junit4] > at
__randomizedtesting.SeedInfo.seed([EC56B329DF05FAC4]:0)Throwable #3:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=1693,
name=TEST-CollectionsAPIDistributedZkTest.testDistribSearch-seed#[EC56B329DF05FAC4]-SendThread(127.0.0.1:47930),
state=TIMED_WAITING, group=TGRP-CollectionsAPIDistributedZkTest]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:994)
[junit4] > at
__randomizedtesting.SeedInfo.seed([EC56B329DF05FAC4]:0)
[junit4] Completed on J1 in 303.75s, 1 test, 1 failure, 3 errors <<<
FAILURES!
[...truncated 816 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:447: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1298:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:922:
There were test failures: 421 suites, 1751 tests, 3 suite-level errors, 1
error, 863 ignored (13 assumptions)
Total time: 58 minutes 43 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.7.0_65
-XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
