You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/02/03 11:26:49 UTC
[JENKINS] Lucene-Solr-4.x-Linux
(64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0) - Build # 9239 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/9239/
Java: 64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0 -XnoOpt
1 tests failed.
REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
Still found collection that should be gone
Stack Trace:
java.lang.AssertionError: Still found collection that should be gone
at __randomizedtesting.SeedInfo.seed([37F600C31C57310F:B6108EDB6B085133]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertFalse(Assert.java:68)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionWithDownNodes(CollectionsAPIDistributedZkTest.java:395)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:203)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:774)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:683)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:44)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:56)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:662)
Build Log:
[...truncated 9923 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> 1037754 T4438 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1037786 T4438 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-CollectionsAPIDistributedZkTest-1391422372350
[junit4] 2> 1037787 T4438 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 1037787 T4439 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1037888 T4438 oasc.ZkTestServer.run start zk server on port:58257
[junit4] 2> 1037889 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038268 T4445 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c68fc83 name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038269 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038269 T4438 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1038277 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038278 T4447 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c6aa5c3 name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038279 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038279 T4438 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1038286 T4438 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1038292 T4438 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1038296 T4438 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1038300 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1038301 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1038308 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1038309 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1038317 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1038319 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1038326 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1038327 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1038332 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1038333 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1038339 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1038340 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1038346 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1038347 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1038354 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1038355 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1038361 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1038361 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1038368 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1038369 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1038375 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1038375 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> Using new style solr.xml
[junit4] 2> 1038384 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038385 T4449 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d6a03b7 name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038385 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038385 T4438 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1038390 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038392 T4451 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d6a3fd7 name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038392 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038393 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf2/solrconfig.xml
[junit4] 2> 1038394 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 1038401 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
[junit4] 2> 1038401 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 1038410 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1038411 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1038417 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
[junit4] 2> 1038418 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 1038426 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
[junit4] 2> 1038427 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 1038434 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
[junit4] 2> 1038435 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 1038443 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
[junit4] 2> 1038443 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 1038450 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1038451 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 1038459 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
[junit4] 2> 1038460 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 1038466 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
[junit4] 2> 1038467 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 1038475 T4438 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
[junit4] 2> 1038475 T4438 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 1038573 T4438 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1038580 T4438 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:37448
[junit4] 2> 1038581 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1038582 T4438 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1038582 T4438 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047
[junit4] 2> 1038583 T4438 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047/'
[junit4] 2> 1038602 T4438 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047/solr.xml
[junit4] 2> 1038647 T4438 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 1038648 T4438 oasc.CoreContainer.<init> New CoreContainer 493790131
[junit4] 2> 1038648 T4438 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047/]
[junit4] 2> 1038649 T4438 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1038649 T4438 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1038650 T4438 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1038650 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1038650 T4438 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1038651 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1038651 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1038651 T4438 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1038652 T4438 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1038654 T4438 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1038654 T4438 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1038654 T4438 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1038655 T4438 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58257/solr
[junit4] 2> 1038655 T4438 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1038656 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038657 T4462 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d5edca3 name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038657 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038660 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038661 T4464 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d5f10aa name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038662 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038665 T4438 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1038673 T4438 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1038680 T4438 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1038684 T4438 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37448_
[junit4] 2> 1038686 T4438 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37448_
[junit4] 2> 1038694 T4438 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1038701 T4438 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1038709 T4438 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:37448_
[junit4] 2> 1038709 T4438 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1038714 T4438 oasc.Overseer.start Overseer (id=91188256594591749-127.0.0.1:37448_-n_0000000000) starting
[junit4] 2> 1038721 T4438 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1038729 T4466 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1038731 T4438 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1038732 T4466 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 1038735 T4438 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1038737 T4438 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1038743 T4465 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1038746 T4438 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047
[junit4] 2> 1038748 T4438 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047/conf/
[junit4] 2> 1038788 T4438 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1038789 T4438 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1038789 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1038790 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038791 T4469 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d8f1eb1 name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038791 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038792 T4438 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1038797 T4438 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1038912 T4438 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 1038912 T4438 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1038914 T4438 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51353
[junit4] 2> 1038915 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1038915 T4438 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1038915 T4438 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361
[junit4] 2> 1038916 T4438 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/'
[junit4] 2> 1038930 T4438 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solr.xml
[junit4] 2> 1038977 T4438 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 1038978 T4438 oasc.CoreContainer.<init> New CoreContainer 480039729
[junit4] 2> 1038978 T4438 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/]
[junit4] 2> 1038979 T4438 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1038980 T4438 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1038980 T4438 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1038980 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1038981 T4438 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1038981 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1038981 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1038982 T4438 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1038982 T4438 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1038984 T4438 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1038984 T4438 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1038985 T4438 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1038985 T4438 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58257/solr
[junit4] 2> 1038985 T4438 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1038986 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038988 T4480 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c9f3302 name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038988 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038990 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1038991 T4482 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c9fd3fd name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1038991 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1038996 T4438 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1040000 T4438 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51353_
[junit4] 2> 1040002 T4438 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51353_
[junit4] 2> 1040006 T4469 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1040006 T4482 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1040007 T4464 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1040016 T4438 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361
[junit4] 2> 1040017 T4438 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/conf/
[junit4] 2> 1040063 T4438 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1040064 T4438 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1040064 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1040157 T4438 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 1040157 T4438 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1040161 T4438 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56363
[junit4] 2> 1040161 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1040162 T4438 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1040162 T4438 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1391422374628
[junit4] 2> 1040162 T4438 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1391422374628/'
[junit4] 2> 1040179 T4438 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1391422374628/solr.xml
[junit4] 2> 1040234 T4438 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 1040235 T4438 oasc.CoreContainer.<init> New CoreContainer 483368038
[junit4] 2> 1040235 T4438 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1391422374628/]
[junit4] 2> 1040237 T4438 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1040237 T4438 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1040237 T4438 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1040238 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1040238 T4438 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1040238 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1040239 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1040239 T4438 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1040239 T4438 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1040242 T4438 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1040242 T4438 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1040243 T4438 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1040243 T4438 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58257/solr
[junit4] 2> 1040243 T4438 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1040244 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1040246 T4494 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ccbad9c name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1040246 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1040248 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1040459 T4496 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ccbe19d name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1040459 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1040467 T4438 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1041472 T4438 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56363_
[junit4] 2> 1041474 T4438 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56363_
[junit4] 2> 1041479 T4496 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1041480 T4482 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1041480 T4469 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1041480 T4464 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1041489 T4438 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1391422374628
[junit4] 2> 1041490 T4438 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1391422374628/conf/
[junit4] 2> 1041528 T4438 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1041528 T4438 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1041529 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1041618 T4438 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 1041619 T4438 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1041621 T4438 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55668
[junit4] 2> 1041621 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1041622 T4438 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1041622 T4438 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092
[junit4] 2> 1041622 T4438 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/'
[junit4] 2> 1041637 T4438 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solr.xml
[junit4] 2> 1041700 T4438 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 1041701 T4438 oasc.CoreContainer.<init> New CoreContainer 476024795
[junit4] 2> 1041701 T4438 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/]
[junit4] 2> 1041703 T4438 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1041704 T4438 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1041704 T4438 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1041704 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1041705 T4438 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1041705 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1041706 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1041706 T4438 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1041707 T4438 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1041709 T4438 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1041710 T4438 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1041710 T4438 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1041711 T4438 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58257/solr
[junit4] 2> 1041711 T4438 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1041712 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1041715 T4508 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c5b5e6b name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1041715 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1041718 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1041720 T4510 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c5b9275 name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1041720 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1041727 T4438 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1042731 T4438 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55668_
[junit4] 2> 1042733 T4438 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55668_
[junit4] 2> 1042737 T4510 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1042738 T4469 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1042739 T4464 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1042739 T4496 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1042743 T4482 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 1042746 T4438 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092
[junit4] 2> 1042748 T4438 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/conf/
[junit4] 2> 1042785 T4438 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1042785 T4438 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1042786 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1042880 T4438 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 1042881 T4438 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1042883 T4438 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60391
[junit4] 2> 1042883 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1042884 T4438 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1042884 T4438 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349
[junit4] 2> 1042885 T4438 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/'
[junit4] 2> 1042902 T4438 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solr.xml
[junit4] 2> 1042955 T4438 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 1042956 T4438 oasc.CoreContainer.<init> New CoreContainer 481160288
[junit4] 2> 1042956 T4438 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/]
[junit4] 2> 1042957 T4438 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1042958 T4438 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1042958 T4438 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1042959 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1042959 T4438 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1042959 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1042960 T4438 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1042960 T4438 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1042961 T4438 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1042963 T4438 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1042963 T4438 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1042964 T4438 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1042964 T4438 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58257/solr
[junit4] 2> 1042965 T4438 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1042965 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1042966 T4522 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1caa4556 name:ZooKeeperConnection Watcher:127.0.0.1:58257 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1042967 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1042970 T4438 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1042971 T4524 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cb0a148 name:ZooKeeperConnection Watcher:127.0.0.1:58257/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1042971 T4438 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1042976 T4438 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1043981 T4438 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60391_
[junit4] 2> 1043982 T4438 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60391_
[junit4] 2> 1043987 T4482 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1043987 T4510 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1043988 T4496 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1043989 T4469 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1043989 T4464 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1043989 T4524 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 1043999 T4438 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349
[junit4] 2> 1044001 T4438 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/conf/
[junit4] 2> 1044049 T4438 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 1044050 T4438 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1044050 T4438 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1044067 T4477 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=2&collection.configName=conf1&name=solrj_collection&replicationFactor=2&action=CREATE&router.field=myOwnField&wt=javabin&router.name=compositeId&version=2
[junit4] 2> 1044068 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044071 T4466 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> 1044071 T4466 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> 1044072 T4466 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection
[junit4] 2> 1044072 T4466 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 1044077 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044079 T4465 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1044080 T4465 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection
[junit4] 2> 1044080 T4465 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2]
[junit4] 2> 1044083 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044084 T4469 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> 1044084 T4482 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> 1044084 T4524 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> 1044084 T4510 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> 1044085 T4496 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> 1044089 T4464 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> 1044177 T4466 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2] , repFactor : 2
[junit4] 2> 1044178 T4466 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:55668_
[junit4] 2> 1044178 T4466 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:37448_
[junit4] 2> 1044179 T4466 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:51353_
[junit4] 2> 1044179 T4466 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:60391_
[junit4] 2> 1044179 T4503 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down
[junit4] 2> 1044180 T4476 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down
[junit4] 2> 1044180 T4518 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down
[junit4] 2> 1044181 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044182 T4503 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1044182 T4458 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down
[junit4] 2> 1044194 T4476 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1044195 T4518 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1044196 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044196 T4458 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1044197 T4465 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1044198 T4465 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:55668",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55668_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1044203 T4465 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:51353",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51353_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1044207 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044209 T4465 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:60391",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60391_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1044212 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044214 T4465 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:37448",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37448_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1044218 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1044321 T4469 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> 1044322 T4510 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> 1044322 T4482 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> 1044322 T4464 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> 1044322 T4524 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> 1044322 T4496 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> 1045182 T4503 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 1045183 T4503 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using instanceDir: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1
[junit4] 2> 1045184 T4503 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1045185 T4503 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1045185 T4503 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1045187 T4503 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/'
[junit4] 2> 1045195 T4476 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 1045195 T4518 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 1045195 T4476 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1
[junit4] 2> 1045196 T4518 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica2' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2
[junit4] 2> 1045196 T4476 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1045196 T4458 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 1045196 T4518 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1045197 T4476 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1045198 T4476 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1045198 T4518 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1045199 T4518 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1045197 T4458 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047/solrj_collection_shard1_replica2
[junit4] 2> 1045199 T4476 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/'
[junit4] 2> 1045200 T4458 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 1045201 T4518 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/'
[junit4] 2> 1045201 T4458 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1045202 T4458 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 1045204 T4458 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1391422373047/solrj_collection_shard1_replica2/'
[junit4] 2> 1045257 T4503 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 1045267 T4476 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 1045294 T4518 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 1045305 T4458 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 1045353 T4503 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1045358 T4503 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1045383 T4476 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1045389 T4476 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1045390 T4503 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 1045394 T4518 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1045401 T4476 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 1045404 T4518 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1045419 T4458 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1045421 T4518 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 1045422 T4458 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1045446 T4458 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 1046252 T4518 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046253 T4518 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046253 T4518 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1046264 T4518 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1046270 T4518 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1046297 T4476 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046297 T4476 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046298 T4476 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1046298 T4503 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046299 T4503 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046300 T4503 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1046305 T4518 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046307 T4476 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1046312 T4518 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046312 T4476 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1046317 T4503 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1046318 T4518 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046321 T4518 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046322 T4518 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046322 T4518 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046325 T4503 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1046325 T4518 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046326 T4518 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046326 T4518 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1046327 T4518 oasc.SolrCore.<init> [solrj_collection_shard2_replica2] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/, dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/data/
[junit4] 2> 1046327 T4518 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c0bc479
[junit4] 2> 1046328 T4518 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/data
[junit4] 2> 1046329 T4518 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/data/index/
[junit4] 2> 1046329 T4518 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica2] Solr index directory '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 1046330 T4518 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1391422377349/solrj_collection_shard2_replica2/data/index
[junit4] 2> 1046331 T4518 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.984375, floorSegmentMB=0.4326171875, forceMergeDeletesPctAllowed=21.156276400953395, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5767239146860972
[junit4] 2> 1046333 T4518 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1c5b3a01 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c5b446d),segFN=segments_1,generation=1}
[junit4] 2> 1046334 T4518 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1046339 T4518 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1046340 T4518 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1046340 T4518 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1046341 T4518 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1046341 T4518 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1046341 T4518 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1046342 T4518 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1046343 T4518 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1046343 T4518 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1046344 T4518 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1046345 T4518 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1046345 T4518 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1046346 T4518 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1046347 T4518 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1046348 T4518 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1046349 T4518 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1046352 T4518 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1046352 T4458 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046353 T4458 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1046353 T4476 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046353 T4458 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1046360 T4518 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1046360 T4518 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1046361 T4476 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046361 T4518 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=18.388671875, floorSegmentMB=2.076171875, forceMergeDeletesPctAllowed=20.72796562361801, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1046362 T4518 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1c5b3a01 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c5b446d),segFN=segments_1,generation=1}
[junit4] 2> 1046363 T4518 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1046363 T4458 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1046365 T4518 oass.SolrIndexSearcher.<init> Opening Searcher@1cbaabc8 main
[junit4] 2> 1046369 T4476 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046369 T4458 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1046372 T4476 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046372 T4530 oasc.SolrCore.registerSearcher [solrj_collection_shard2_replica2] Registered new searcher Searcher@1cbaabc8 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1046373 T4476 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046373 T4503 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046377 T4476 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046379 T4518 oasc.CoreContainer.registerCore registering core: solrj_collection_shard2_replica2
[junit4] 2> 1046380 T4518 oasc.ZkController.register Register replica - core:solrj_collection_shard2_replica2 address:http://127.0.0.1:60391 collection:solrj_collection shard:shard2
[junit4] 2> 1046382 T4476 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046382 T4476 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046383 T4476 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1046383 T4476 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/, dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/data/
[junit4] 2> 1046384 T4476 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c0bc479
[junit4] 2> 1046384 T4476 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/data
[junit4] 2> 1046385 T4476 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 1046385 T4476 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1046385 T4476 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1391422373361/solrj_collection_shard2_replica1/data/index
[junit4] 2> 1046386 T4503 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046386 T4476 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.984375, floorSegmentMB=0.4326171875, forceMergeDeletesPctAllowed=21.156276400953395, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5767239146860972
[junit4] 2> 1046387 T4518 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection/leader_elect/shard2/election
[junit4] 2> 1046388 T4476 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1cbfe7ec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cbff258),segFN=segments_1,generation=1}
[junit4] 2> 1046388 T4476 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1046391 T4476 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1046392 T4476 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1046392 T4476 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1046392 T4476 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1046393 T4476 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1046393 T4476 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1046393 T4476 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1046394 T4476 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1046394 T4476 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1046395 T4503 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046395 T4476 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1046396 T4476 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1046396 T4476 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1046397 T4476 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1046397 T4476 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1046398 T4476 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1046399 T4476 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1046400 T4503 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046401 T4476 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1046401 T4503 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046402 T4503 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046405 T4503 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046405 T4503 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046406 T4476 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1046406 T4503 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1046406 T4458 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046406 T4476 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1046406 T4503 oasc.SolrCore.<init> [solrj_collection_shard1_replica1] Opening new SolrCore at ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/, dataDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/data/
[junit4] 2> 1046408 T4503 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c0bc479
[junit4] 2> 1046408 T4476 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=18.388671875, floorSegmentMB=2.076171875, forceMergeDeletesPctAllowed=20.72796562361801, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1046409 T4503 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/data
[junit4] 2> 1046409 T4503 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/data/index/
[junit4] 2> 1046409 T4476 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1cbfe7ec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cbff258),segFN=segments_1,generation=1}
[junit4] 2> 1046410 T4503 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica1] Solr index directory './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1046410 T4476 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1046409 T4518 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1046411 T4503 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1391422376092/solrj_collection_shard1_replica1/data/index
[junit4] 2> 1046412 T4476 oass.SolrIndexSearcher.<init> Opening Searcher@1cc44863 main
[junit4] 2> 1046412 T4503 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=39.984375, floorSegmentMB=0.4326171875, forceMergeDeletesPctAllowed=21.156276400953395, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5767239146860972
[junit4] 2> 1046413 T4458 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1046415 T4503 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1cb30af8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cb31564),segFN=segments_1,generation=1}
[junit4] 2> 1046415 T4503 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1046415 T4518 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=180000
[junit4] 2> 1046415 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1046416 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1046416 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1046417 T4532 oasc.SolrCore.registerSearcher [solrj_collection_shard2_replica1] Registered new searcher Searcher@1cc44863 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1046419 T4465 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1046419 T4503 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1046420 T4476 oasc.CoreContainer.registerCore registering core: solrj_collection_shard2_replica1
[junit4] 2> 1046420 T4503 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1046420 T4476 oasc.ZkController.register Register replica - core:solrj_collection_shard2_replica1 address:http://127.0.0.1:51353 collection:solrj_collection shard:shard2
[junit4] 2> 1046421 T4503 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1046421 T4503 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1046422 T4503 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1046422 T4503 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1046423 T4503 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1046423 T4464 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1046423 T4503 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1046423 T4458 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046424 T4503 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1046425 T4469 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> 1046425 T4496 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> 1046433 T4482 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> 1046425 T4524 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> 1046425 T4510 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> 1046436 T4464 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> 1046438 T4503 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1046439 T4503 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1046440 T4503 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1046440 T4458 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046440 T4503 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1046440 T4458 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046441 T4503 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1046441 T4458 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1046442 T4503 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1046443 T4503 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1046444 T4458 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1046445 T4503 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1046445 T4458 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1046445 T4458 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[ju
[...truncated too long message...]
for /collections
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1041)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:205)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:202)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:202)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:413)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:382)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:369)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:112)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:273)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 1145966 T4524 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/awholynewcollection_2/leader_elect/shard2/election/91188256594591758-core_node8-n_0000000001
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:173)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:170)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:170)
[junit4] 2> at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:67)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:293)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 1145966 T4524 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:92)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 1145967 T4524 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/nodes_used_collection/leader_elect/shard1/election
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:92)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 1145968 T4524 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=es_HN, timezone=SystemV/YST9
[junit4] 2> NOTE: Linux 3.8.0-35-generic amd64/Oracle Corporation 1.6.0_45 (64-bit)/cpus=8,threads=2,free=184184192,total=371834880
[junit4] 2> NOTE: All tests run in this JVM: [TriLevelCompositeIdRoutingTest, BJQParserTest, IndexSchemaTest, CoreAdminHandlerTest, OverseerTest, TestSolrIndexConfig, TestElisionMultitermQuery, InfoHandlerTest, LoggingHandlerTest, TestSystemIdResolver, SuggesterTest, TestCharFilters, SearchHandlerTest, CircularListTest, CSVRequestHandlerTest, UpdateRequestProcessorFactoryTest, TestAddFieldRealTimeGet, TestNonNRTOpen, TestPerFieldSimilarity, UnloadDistributedZkTest, QueryParsingTest, TestClassNameShortening, SampleTest, MBeansHandlerTest, ScriptEngineTest, ZkCLITest, TestJmxMonitoredMap, AssignTest, SuggesterFSTTest, SchemaVersionSpecificBehaviorTest, ZkControllerTest, TestRandomDVFaceting, ChangedSchemaMergeTest, EnumFieldTest, SliceStateTest, CoreAdminCreateDiscoverTest, CacheHeaderTest, LegacyHTMLStripCharFilterTest, TestReplicationHandler, TestInfoStreamLogging, SyncSliceTest, ParsingFieldUpdateProcessorsTest, LeaderElectionTest, SolrCoreTest, DistributedQueryElevationComponentTest, TestSearchPerf, StatelessScriptUpdateProcessorFactoryTest, QueryResultKeyTest, ShardRoutingTest, TestExtendedDismaxParser, TestBadConfig, PeerSyncTest, ShowFileRequestHandlerTest, BinaryUpdateRequestHandlerTest, TestLuceneMatchVersion, BasicDistributedZkTest, TestBM25SimilarityFactory, OverseerCollectionProcessorTest, TestPostingsSolrHighlighter, EchoParamsTest, DeleteReplicaTest, TestOverriddenPrefixQueryForCustomFieldType, QueryElevationComponentTest, TestSchemaNameResource, TestUniqueKeyFieldResource, OverseerRolesTest, DirectUpdateHandlerOptimizeTest, TestSolrQueryParserResource, SolrIndexSplitterTest, DeleteShardTest, CopyFieldTest, ResponseLogComponentTest, DocValuesTest, DocumentBuilderTest, MoreLikeThisHandlerTest, TestStressVersions, TestSurroundQueryParser, TestJoin, FieldMutatingUpdateProcessorTest, TestCoreContainer, TestSuggestSpellingConverter, TestStressUserVersions, DistributedSpellCheckComponentTest, ClusterStateTest, CollectionsAPIDistributedZkTest]
[junit4] Completed on J0 in 108.46s, 1 test, 1 failure <<< FAILURES!
[...truncated 542 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:439: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:941: There were test failures: 355 suites, 1549 tests, 1 failure, 35 ignored (7 assumptions)
Total time: 75 minutes 16 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jrockit-jdk1.6.0_45-R28.2.7-4.1.0 -XnoOpt
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure