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/01/20 07:33:16 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_51) - Build # 9062
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/9062/
Java: 32bit/jdk1.7.0_51 -server -XX:+UseConcMarkSweepGC
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([C12758725CAB05BB:40C1D66A2BF46587]: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:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
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:682)
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:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 10491 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> 373538 T1414 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 373542 T1414 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-CollectionsAPIDistributedZkTest-1390198512072
[junit4] 2> 373542 T1414 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 373543 T1415 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 373643 T1414 oasc.ZkTestServer.run start zk server on port:40317
[junit4] 2> 373644 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 373670 T1421 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15272c7 name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 373670 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 373671 T1414 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 373679 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 373681 T1423 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@85b9b6 name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 373681 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 373682 T1414 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 373687 T1414 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 373691 T1414 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 373695 T1414 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 373703 T1414 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> 373704 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 373710 T1414 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> 373710 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 373713 T1414 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> 373714 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 373716 T1414 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> 373716 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 373720 T1414 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> 373720 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 373726 T1414 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> 373726 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 373731 T1414 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> 373731 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 373736 T1414 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> 373737 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 373741 T1414 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> 373742 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 373746 T1414 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> 373747 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 373751 T1414 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> 373752 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> Using new style solr.xml
[junit4] 2> 373757 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 373759 T1425 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ab3943 name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 373760 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 373760 T1414 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 373765 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 373767 T1427 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13b3005 name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 373767 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 373768 T1414 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> 373768 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 373774 T1414 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> 373775 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 373779 T1414 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> 373780 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 373784 T1414 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> 373784 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 373786 T1414 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> 373787 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 373792 T1414 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> 373793 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 373797 T1414 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> 373798 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 373802 T1414 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> 373803 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 373808 T1414 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> 373809 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 373813 T1414 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> 373814 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 373819 T1414 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> 373820 T1414 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 373901 T1414 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 373903 T1414 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33117
[junit4] 2> 373904 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 373904 T1414 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 373905 T1414 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356
[junit4] 2> 373905 T1414 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/'
[junit4] 2> 373919 T1414 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solr.xml
[junit4] 2> 373957 T1414 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 373959 T1414 oasc.CoreContainer.<init> New CoreContainer 11563958
[junit4] 2> 373959 T1414 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/]
[junit4] 2> 373960 T1414 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 373960 T1414 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 373961 T1414 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 373961 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 373961 T1414 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 373962 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 373962 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 373962 T1414 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 373962 T1414 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 373965 T1414 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 373965 T1414 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 373965 T1414 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 373966 T1414 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:40317/solr
[junit4] 2> 373966 T1414 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 373967 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 373968 T1438 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7cc177 name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 373969 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 373971 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 373972 T1440 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1408401 name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 373972 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 373974 T1414 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 373977 T1414 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 373979 T1414 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 373980 T1414 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33117_
[junit4] 2> 373981 T1414 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33117_
[junit4] 2> 373983 T1414 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 373985 T1414 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 373989 T1414 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 373992 T1414 oasc.Overseer.start Overseer (id=91108049687412741-127.0.0.1:33117_-n_0000000000) starting
[junit4] 2> 373995 T1414 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 373998 T1442 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 373998 T1414 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 373998 T1442 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 374000 T1414 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 374001 T1414 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 374003 T1441 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 374005 T1414 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356
[junit4] 2> 374007 T1414 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/conf/
[junit4] 2> 374032 T1414 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 374032 T1414 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 374033 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 374034 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 374035 T1445 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14611d3 name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 374035 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 374037 T1414 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 374039 T1414 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 374101 T1414 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 374102 T1414 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42994
[junit4] 2> 374103 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 374103 T1414 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 374104 T1414 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569
[junit4] 2> 374104 T1414 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/'
[junit4] 2> 374114 T1414 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solr.xml
[junit4] 2> 374149 T1414 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 374150 T1414 oasc.CoreContainer.<init> New CoreContainer 3428010
[junit4] 2> 374150 T1414 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/]
[junit4] 2> 374151 T1414 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 374151 T1414 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 374152 T1414 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 374152 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 374152 T1414 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 374152 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 374153 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 374153 T1414 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 374153 T1414 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 374156 T1414 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 374156 T1414 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 374156 T1414 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 374157 T1414 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:40317/solr
[junit4] 2> 374157 T1414 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 374158 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 374159 T1456 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@188649 name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 374159 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 374162 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 374163 T1458 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f564d0 name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 374163 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 374166 T1414 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 375169 T1414 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42994_
[junit4] 2> 375170 T1414 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42994_
[junit4] 2> 375172 T1440 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 375173 T1458 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 375173 T1445 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 375177 T1414 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569
[junit4] 2> 375178 T1414 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/conf/
[junit4] 2> 375198 T1414 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 375198 T1414 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 375199 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 375264 T1414 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 375266 T1414 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56448
[junit4] 2> 375267 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 375267 T1414 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 375267 T1414 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729
[junit4] 2> 375268 T1414 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/'
[junit4] 2> 375278 T1414 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solr.xml
[junit4] 2> 375315 T1414 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 375315 T1414 oasc.CoreContainer.<init> New CoreContainer 1559424
[junit4] 2> 375316 T1414 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/]
[junit4] 2> 375316 T1414 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 375317 T1414 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 375317 T1414 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 375317 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 375318 T1414 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 375318 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 375318 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 375318 T1414 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 375319 T1414 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 375321 T1414 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 375321 T1414 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 375322 T1414 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 375322 T1414 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:40317/solr
[junit4] 2> 375322 T1414 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 375323 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 375325 T1470 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@315a31 name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 375325 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 375328 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 375333 T1472 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@56df3f name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 375334 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 375338 T1414 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 376341 T1414 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56448_
[junit4] 2> 376344 T1414 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56448_
[junit4] 2> 376349 T1440 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 376349 T1445 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 376349 T1472 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 376349 T1458 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 376358 T1414 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729
[junit4] 2> 376360 T1414 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/conf/
[junit4] 2> 376387 T1414 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 376387 T1414 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 376387 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 376462 T1414 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 376465 T1414 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46472
[junit4] 2> 376466 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 376467 T1414 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 376467 T1414 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918
[junit4] 2> 376468 T1414 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/'
[junit4] 2> 376487 T1414 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solr.xml
[junit4] 2> 376522 T1414 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 376523 T1414 oasc.CoreContainer.<init> New CoreContainer 9927904
[junit4] 2> 376523 T1414 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/]
[junit4] 2> 376524 T1414 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 376524 T1414 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 376525 T1414 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 376525 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 376525 T1414 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 376525 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 376526 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 376526 T1414 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 376526 T1414 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 376528 T1414 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 376529 T1414 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 376529 T1414 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 376529 T1414 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:40317/solr
[junit4] 2> 376530 T1414 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 376530 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 376533 T1484 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18791ed name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 376534 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 376537 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 376538 T1486 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b182ac name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 376538 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 376544 T1414 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 377548 T1414 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46472_
[junit4] 2> 377550 T1414 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46472_
[junit4] 2> 377554 T1445 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 377554 T1440 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 377555 T1458 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 377555 T1472 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 377555 T1486 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 377564 T1414 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918
[junit4] 2> 377565 T1414 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/conf/
[junit4] 2> 377598 T1414 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 377598 T1414 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 377599 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 377679 T1414 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 377681 T1414 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45080
[junit4] 2> 377682 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 377682 T1414 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 377682 T1414 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129
[junit4] 2> 377683 T1414 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/'
[junit4] 2> 377695 T1414 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/solr.xml
[junit4] 2> 377735 T1414 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 377735 T1414 oasc.CoreContainer.<init> New CoreContainer 26707364
[junit4] 2> 377735 T1414 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/]
[junit4] 2> 377736 T1414 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 377737 T1414 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 377737 T1414 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 377737 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 377738 T1414 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 377738 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 377738 T1414 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 377738 T1414 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 377739 T1414 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 377741 T1414 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 377741 T1414 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 377742 T1414 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 377742 T1414 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:40317/solr
[junit4] 2> 377742 T1414 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 377743 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 377764 T1498 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c6e543 name:ZooKeeperConnection Watcher:127.0.0.1:40317 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 377765 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 377769 T1414 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 377771 T1500 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@136de5 name:ZooKeeperConnection Watcher:127.0.0.1:40317/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 377771 T1414 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 377780 T1414 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 378786 T1414 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45080_
[junit4] 2> 378788 T1414 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45080_
[junit4] 2> 378790 T1458 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 378790 T1472 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 378790 T1445 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 378790 T1486 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 378790 T1440 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 378792 T1500 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 378797 T1414 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129
[junit4] 2> 378798 T1414 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/conf/
[junit4] 2> 378826 T1414 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 378827 T1414 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 378827 T1414 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 378835 T1451 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&numShards=2&name=solrj_collection&action=CREATE&version=2&wt=javabin&router.name=compositeId&collection.configName=conf1&router.field=myOwnField
[junit4] 2> 378837 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378838 T1442 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.name":"compositeId",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 378839 T1442 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.name":"compositeId",
[junit4] 2> "router.field":"myOwnField"}
[junit4] 2> 378839 T1442 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection
[junit4] 2> 378839 T1442 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 378842 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378843 T1441 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 378844 T1441 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection
[junit4] 2> 378844 T1441 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2]
[junit4] 2> 378846 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378847 T1458 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> 378847 T1486 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> 378847 T1440 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> 378847 T1472 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> 378847 T1445 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> 378847 T1500 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> 378943 T1442 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2] , repFactor : 2
[junit4] 2> 378943 T1442 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:42994_
[junit4] 2> 378943 T1442 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:56448_
[junit4] 2> 378944 T1442 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:33117_
[junit4] 2> 378945 T1442 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:46472_
[junit4] 2> 378947 T1468 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down
[junit4] 2> 378947 T1453 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down
[junit4] 2> 378947 T1434 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down
[junit4] 2> 378948 T1479 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down
[junit4] 2> 378948 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378949 T1468 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 378950 T1453 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 378950 T1479 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 378950 T1434 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 378951 T1441 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 378952 T1441 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:56448",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56448_",
[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> 378954 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378955 T1441 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:42994",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42994_",
[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> 378957 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378958 T1441 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:33117",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33117_",
[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> 378961 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 378962 T1441 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:46472",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46472_",
[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> 378964 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 379067 T1472 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> 379067 T1500 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> 379067 T1445 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> 379067 T1486 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> 379067 T1440 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> 379067 T1458 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> 379949 T1468 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 379950 T1468 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using instanceDir: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2
[junit4] 2> 379950 T1468 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 379951 T1453 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 379951 T1479 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 379951 T1468 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 379952 T1468 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 379952 T1479 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica2' using instanceDir: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2
[junit4] 2> 379952 T1479 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 379951 T1434 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 379953 T1479 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 379953 T1468 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/'
[junit4] 2> 379951 T1453 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using instanceDir: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1
[junit4] 2> 379954 T1453 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 379953 T1434 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using instanceDir: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1
[junit4] 2> 379953 T1479 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 379954 T1453 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 379955 T1453 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 379954 T1434 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 379955 T1479 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/'
[junit4] 2> 379957 T1434 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 379957 T1453 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/'
[junit4] 2> 379957 T1434 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 379958 T1434 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/'
[junit4] 2> 380019 T1434 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 380023 T1468 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 380023 T1453 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 380026 T1479 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 380087 T1434 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 380089 T1434 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 380102 T1434 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 380119 T1468 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 380120 T1468 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 380127 T1468 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 380127 T1479 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 380128 T1453 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 380134 T1453 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 380134 T1479 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 380143 T1453 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 380143 T1479 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 380737 T1434 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380739 T1434 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380739 T1434 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 380746 T1434 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 380753 T1434 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 380772 T1434 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380775 T1434 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380779 T1434 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380780 T1434 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380781 T1434 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380781 T1434 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380787 T1434 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380788 T1434 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380788 T1434 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 380789 T1434 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/, dataDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/data/
[junit4] 2> 380789 T1434 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@eea27d
[junit4] 2> 380791 T1434 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/data
[junit4] 2> 380791 T1434 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 380792 T1434 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 380792 T1434 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1390198512356/solrj_collection_shard2_replica1/data/index
[junit4] 2> 380793 T1434 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 380793 T1434 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@ff89b9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f19cb7),segFN=segments_1,generation=1}
[junit4] 2> 380794 T1434 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 380796 T1453 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380797 T1453 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380798 T1453 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 380798 T1434 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 380798 T1434 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 380799 T1434 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 380799 T1434 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 380799 T1434 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 380800 T1434 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 380806 T1434 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 380806 T1434 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 380807 T1434 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 380807 T1434 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 380808 T1434 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 380809 T1434 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 380809 T1434 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 380810 T1434 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 380810 T1453 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 380811 T1434 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 380811 T1434 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 380813 T1453 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 380815 T1434 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 380818 T1434 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 380819 T1434 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 380820 T1434 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8693385898706628]
[junit4] 2> 380821 T1434 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@ff89b9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f19cb7),segFN=segments_1,generation=1}
[junit4] 2> 380821 T1434 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 380822 T1434 oass.SolrIndexSearcher.<init> Opening Searcher@12e3d70 main
[junit4] 2> 380830 T1506 oasc.SolrCore.registerSearcher [solrj_collection_shard2_replica1] Registered new searcher Searcher@12e3d70 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 380831 T1453 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380833 T1434 oasc.CoreContainer.registerCore registering core: solrj_collection_shard2_replica1
[junit4] 2> 380834 T1434 oasc.ZkController.register Register replica - core:solrj_collection_shard2_replica1 address:http://127.0.0.1:33117 collection:solrj_collection shard:shard2
[junit4] 2> 380835 T1453 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380835 T1434 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection/leader_elect/shard2/election
[junit4] 2> 380838 T1453 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380840 T1453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380840 T1453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380840 T1453 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380841 T1453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380842 T1453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380841 T1434 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 380847 T1453 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 380848 T1453 oasc.SolrCore.<init> [solrj_collection_shard1_replica1] Opening new SolrCore at ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/, dataDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/data/
[junit4] 2> 380848 T1479 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380848 T1453 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@eea27d
[junit4] 2> 380849 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 380849 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 380850 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 380850 T1434 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=179999
[junit4] 2> 380848 T1479 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380851 T1441 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 380849 T1453 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/data
[junit4] 2> 380852 T1479 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 380852 T1453 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/data/index/
[junit4] 2> 380853 T1453 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica1] Solr index directory './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 380853 T1453 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1390198512569/solrj_collection_shard1_replica1/data/index
[junit4] 2> 380854 T1453 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 380854 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 380855 T1486 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> 380855 T1500 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> 380855 T1472 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> 380855 T1453 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1da0875 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1340240),segFN=segments_1,generation=1}
[junit4] 2> 380856 T1453 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 380855 T1445 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> 380856 T1458 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> 380856 T1440 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> 380861 T1479 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 380863 T1453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 380863 T1453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 380864 T1453 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 380864 T1453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 380865 T1453 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 380865 T1453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 380866 T1479 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 380866 T1453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 380866 T1453 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 380867 T1453 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 380867 T1468 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380868 T1468 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 380868 T1453 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 380868 T1468 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 380869 T1453 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 380869 T1453 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 380869 T1453 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 380870 T1453 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 380871 T1453 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 380871 T1453 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 380872 T1453 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 380875 T1453 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 380876 T1453 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 380876 T1453 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8693385898706628]
[junit4] 2> 380877 T1453 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1da0875 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1340240),segFN=segments_1,generation=1}
[junit4] 2> 380877 T1453 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 380878 T1453 oass.SolrIndexSearcher.<init> Opening Searcher@174809f main
[junit4] 2> 380883 T1479 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380884 T1468 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 380885 T1508 oasc.SolrCore.registerSearcher [solrj_collection_shard1_replica1] Registered new searcher Searcher@174809f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 380887 T1479 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380888 T1453 oasc.CoreContainer.registerCore registering core: solrj_collection_shard1_replica1
[junit4] 2> 380888 T1468 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 380888 T1453 oasc.ZkController.register Register replica - core:solrj_collection_shard1_replica1 address:http://127.0.0.1:42994 collection:solrj_collection shard:shard1
[junit4] 2> 380890 T1453 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection/leader_elect/shard1/election
[junit4] 2> 380891 T1479 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380892 T1479 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380892 T1479 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380892 T1479 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380894 T1479 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380894 T1479 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380894 T1479 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 380895 T1479 oasc.SolrCore.<init> [solrj_collection_shard2_replica2] Opening new SolrCore at ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/, dataDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/data/
[junit4] 2> 380895 T1479 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@eea27d
[junit4] 2> 380896 T1479 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/data
[junit4] 2> 380896 T1479 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/data/index/
[junit4] 2> 380896 T1479 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica2] Solr index directory './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 380897 T1479 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1390198514918/solrj_collection_shard2_replica2/data/index
[junit4] 2> 380897 T1479 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 380898 T1479 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1fc4784 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@621522),segFN=segments_1,generation=1}
[junit4] 2> 380898 T1479 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 380904 T1479 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 380904 T1479 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 380905 T1479 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 380905 T1479 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 380906 T1479 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 380906 T1479 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 380907 T1479 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 380907 T1479 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 380908 T1479 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 380909 T1479 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 380909 T1479 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 380910 T1479 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 380910 T1479 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 380911 T1479 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 380912 T1479 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 380912 T1479 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 380914 T1479 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 380918 T1479 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 380918 T1479 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 380919 T1479 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8693385898706628]
[junit4] 2> 380920 T1479 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1fc4784 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@621522),segFN=segments_1,generation=1}
[junit4] 2> 380920 T1479 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 380921 T1479 oass.SolrIndexSearcher.<init> Opening Searcher@1ac23fb main
[junit4] 2> 380924 T1510 oasc.SolrCore.registerSearcher [solrj_collection_shard2_replica2] Registered new searcher Searcher@1ac23fb main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 380927 T1479 oasc.CoreContainer.registerCore registering core: solrj_collection_shard2_replica2
[junit4] 2> 380928 T1479 oasc.ZkController.register Register replica - core:solrj_collection_shard2_replica2 address:http://127.0.0.1:46472 collection:solrj_collection shard:shard2
[junit4] 2> 380959 T1453 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 380962 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 380963 T1453 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=180000
[junit4] 2> 380964 T1441 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 380967 T1440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 380970 T1468 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380973 T1468 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 380976 T1468 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380977 T1468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380978 T1468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380978 T1468 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 380979 T1468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 380979 T1468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 380979 T1468 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 380980 T1468 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/, dataDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/data/
[junit4] 2> 380981 T1468 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@eea27d
[junit4] 2> 380982 T1468 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/data
[junit4] 2> 380982 T1468 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/data/index/
[junit4] 2> 380983 T1468 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica2] Solr index directory './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 380983 T1468 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1390198513729/solrj_collection_shard1_replica2/data/index
[junit4] 2> 380984 T1468 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 380984 T1468 oasc.SolrDeletionPo
[...truncated too long message...]
core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/nodes_used_collection_shard2_replica1/data/index [CachedDir<<refCount=0;path=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/nodes_used_collection_shard2_replica1/data/index;done=false>>]
[junit4] 2> 492334 T1414 oasc.CachingDirectoryFactory.close Closing directory: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/nodes_used_collection_shard2_replica1/data/index
[junit4] 2> 492334 T1855 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:45080",
[junit4] 2> "core":"awholynewcollection_0_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:45080_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"awholynewcollection_0",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 492334 T1414 oasc.CachingDirectoryFactory.closeCacheValue looking to close ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/nodes_used_collection_shard2_replica1/data [CachedDir<<refCount=0;path=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/nodes_used_collection_shard2_replica1/data;done=false>>]
[junit4] 2> 492335 T1414 oasc.CachingDirectoryFactory.close Closing directory: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/nodes_used_collection_shard2_replica1/data
[junit4] 2> 492335 T1414 oasc.SolrCore.close [awholynewcollection_0_shard2_replica2] CLOSING SolrCore org.apache.solr.core.SolrCore@19fd6e2
[junit4] 2> 492338 T1500 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 492349 T1414 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 492350 T1414 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 492350 T1414 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 492350 T1414 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 492351 T1414 oasc.SolrCore.closeSearcher [awholynewcollection_0_shard2_replica2] Closing main searcher on request.
[junit4] 2> 492351 T1414 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 492351 T1414 oasc.CachingDirectoryFactory.closeCacheValue looking to close ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/awholynewcollection_0_shard2_replica2/data [CachedDir<<refCount=0;path=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/awholynewcollection_0_shard2_replica2/data;done=false>>]
[junit4] 2> 492352 T1414 oasc.CachingDirectoryFactory.close Closing directory: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/awholynewcollection_0_shard2_replica2/data
[junit4] 2> 492352 T1414 oasc.CachingDirectoryFactory.closeCacheValue looking to close ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/awholynewcollection_0_shard2_replica2/data/index [CachedDir<<refCount=0;path=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/awholynewcollection_0_shard2_replica2/data/index;done=false>>]
[junit4] 2> 492352 T1414 oasc.CachingDirectoryFactory.close Closing directory: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1390198516129/awholynewcollection_0_shard2_replica2/data/index
[junit4] 2> 492375 T1414 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 492429 T1414 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 492433 T1414 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:40317 40317
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 1> client port:0.0.0.0/0.0.0.0:40317
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=C12758725CAB05BB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Asia/Riyadh87 -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 119s J1 | CollectionsAPIDistributedZkTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Still found collection that should be gone
[junit4] > at __randomizedtesting.SeedInfo.seed([C12758725CAB05BB:40C1D66A2BF46587]:0)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionWithDownNodes(CollectionsAPIDistributedZkTest.java:395)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:203)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 492560 T1414 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 119031 T1413 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> 493632 T1440 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 493855 T1500 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=zh, timezone=Asia/Riyadh87
[junit4] 2> NOTE: Linux 3.8.0-35-generic i386/Oracle Corporation 1.7.0_51 (32-bit)/cpus=8,threads=3,free=47692504,total=204603392
[junit4] 2> NOTE: All tests run in this JVM: [TestSweetSpotSimilarityFactory, TestRandomFaceting, HighlighterTest, CachingDirectoryFactoryTest, DateFieldTest, DistributedQueryComponentCustomSortTest, TestSchemaResource, RequiredFieldsTest, UniqFieldsUpdateProcessorFactoryTest, PreAnalyzedFieldTest, ChaosMonkeyNothingIsSafeTest, TestDistributedMissingSort, ZkSolrClientTest, TestUniqueKeyFieldResource, ZkNodePropsTest, LeaderElectionIntegrationTest, IndexSchemaRuntimeFieldTest, TestCodecSupport, TestDefaultSearchFieldResource, TestQueryTypes, SortByFunctionTest, TestXIncludeConfig, AlternateDirectoryTest, ShardRoutingCustomTest, TestInfoStreamLogging, CoreMergeIndexesAdminHandlerTest, HardAutoCommitTest, ConnectionManagerTest, XmlUpdateRequestHandlerTest, TestDistribDocBasedVersion, PathHierarchyTokenizerFactoryTest, SolrRequestParserTest, BasicDistributedZk2Test, EnumFieldTest, TestManagedSchemaFieldResource, TestJmxIntegration, TestUtils, RAMDirectoryFactoryTest, CollectionsAPIDistributedZkTest]
[junit4] Completed on J1 in 120.57s, 1 test, 1 failure <<< FAILURES!
[...truncated 707 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: 354 suites, 1540 tests, 1 failure, 34 ignored (6 assumptions)
Total time: 48 minutes 44 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_51 -server -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure