[JENKINS] Lucene-Solr-4.x-Linux (32bit/ibm-j9-jdk7) - Build # 7699 - Failure!

classic Classic list List threaded Threaded
1 message Options
Reply | Threaded
Open this post in threaded view
|

[JENKINS] Lucene-Solr-4.x-Linux (32bit/ibm-j9-jdk7) - Build # 7699 - Failure!

Policeman Jenkins Server-2
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7699/
Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

1 tests failed.
FAILED:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
Expected content type application/octet-stream but got text/html;charset=ISO-8859-1. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 Can not find: /onenodecollectioncore/update</title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /onenodecollectioncore/update. Reason: <pre>    Can not find: /onenodecollectioncore/update</pre></p> <hr /><i><small>Powered by Jetty://</small></i>                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                                     </body> </html>

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected content type application/octet-stream but got text/html;charset=ISO-8859-1. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 Can not find: /onenodecollectioncore/update</title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /onenodecollectioncore/update. Reason:
<pre>    Can not find: /onenodecollectioncore/update</pre></p>
<hr /><i><small>Powered by Jetty://</small></i>
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
                                               
</body>
</html>

        at __randomizedtesting.SeedInfo.seed([DF7480F8F63CBFF7:5E920EE08163DFCB]:0)
        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:427)
        at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
        at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
        at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
        at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
        at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
        at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:88)
        at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
        at java.lang.reflect.Method.invoke(Method.java:613)
        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:780)




Build Log:
[...truncated 10354 lines...]
   [junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
   [junit4]   2> 676885 T2527 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
   [junit4]   2> 676888 T2527 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1381239935832
   [junit4]   2> 676889 T2527 oasc.ZkTestServer.run STARTING ZK TEST SERVER
   [junit4]   2> 676890 T2528 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
   [junit4]   2> 676990 T2527 oasc.ZkTestServer.run start zk server on port:43378
   [junit4]   2> 676991 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 677000 T2534 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ff08e5d6 name:ZooKeeperConnection Watcher:127.0.0.1:43378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 677001 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 677001 T2527 oascc.SolrZkClient.makePath makePath: /solr
   [junit4]   2> 677006 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 677007 T2536 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@62fd3def name:ZooKeeperConnection Watcher:127.0.0.1:43378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 677007 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 677008 T2527 oascc.SolrZkClient.makePath makePath: /collections/collection1
   [junit4]   2> 677011 T2527 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
   [junit4]   2> 677013 T2527 oascc.SolrZkClient.makePath makePath: /collections/control_collection
   [junit4]   2> 677016 T2527 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
   [junit4]   2> 677019 T2527 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> 677019 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 677023 T2527 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> 677024 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
   [junit4]   2> 677027 T2527 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> 677028 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 677031 T2527 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> 677032 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 677035 T2527 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> 677038 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
   [junit4]   2> 677041 T2527 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> 677042 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
   [junit4]   2> 677044 T2527 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> 677045 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 677048 T2527 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> 677049 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 677051 T2527 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> 677053 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 677055 T2527 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> 677056 T2527 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 677170 T2527 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 677173 T2527 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36807
   [junit4]   2> 677174 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 677175 T2527 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 677176 T2527 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002
   [junit4]   2> 677177 T2527 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/'
   [junit4]   2> 677189 T2527 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/solr.xml
   [junit4]   2> 677239 T2527 oasc.CoreContainer.<init> New CoreContainer 725443074
   [junit4]   2> 677240 T2527 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/]
   [junit4]   2> 677242 T2527 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 677243 T2527 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 677244 T2527 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 677245 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 677246 T2527 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 677247 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 677248 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 677249 T2527 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 677250 T2527 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 677251 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 677253 T2527 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 677255 T2527 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 677257 T2527 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43378/solr
   [junit4]   2> 677258 T2527 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 677260 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 677261 T2547 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54ae1d2e name:ZooKeeperConnection Watcher:127.0.0.1:43378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 677261 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 677263 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 677266 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 677267 T2549 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e318b127 name:ZooKeeperConnection Watcher:127.0.0.1:43378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 677267 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 677270 T2527 oascc.SolrZkClient.makePath makePath: /overseer/queue
   [junit4]   2> 677273 T2527 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
   [junit4]   2> 677277 T2527 oascc.SolrZkClient.makePath makePath: /live_nodes
   [junit4]   2> 677280 T2527 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36807_
   [junit4]   2> 677281 T2527 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36807_
   [junit4]   2> 677287 T2527 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
   [junit4]   2> 677294 T2527 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
   [junit4]   2> 677297 T2527 oasc.Overseer.start Overseer (id=90520940434882563-127.0.0.1:36807_-n_0000000000) starting
   [junit4]   2> 677301 T2527 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
   [junit4]   2> 677305 T2551 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
   [junit4]   2> 677305 T2527 oascc.SolrZkClient.makePath makePath: /clusterstate.json
   [junit4]   2> 677308 T2527 oascc.SolrZkClient.makePath makePath: /aliases.json
   [junit4]   2> 677310 T2527 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
   [junit4]   2> 677314 T2550 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
   [junit4]   2> 677317 T2552 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 677318 T2552 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 677319 T2552 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 678817 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 678818 T2550 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2>  "operation":"state",
   [junit4]   2>  "state":"down",
   [junit4]   2>  "base_url":"http://127.0.0.1:36807",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:36807_",
   [junit4]   2>  "shard":null,
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"control_collection",
   [junit4]   2>  "numShards":"1",
   [junit4]   2>  "core_node_name":"core_node1"}
   [junit4]   2> 678818 T2550 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
   [junit4]   2> 678819 T2550 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 678882 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 679320 T2552 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 679320 T2552 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/collection1
   [junit4]   2> 679321 T2552 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
   [junit4]   2> 679321 T2552 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 679322 T2552 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
   [junit4]   2> 679323 T2552 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/collection1/'
   [junit4]   2> 679324 T2552 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/collection1/lib/classes/' to classloader
   [junit4]   2> 679324 T2552 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/collection1/lib/README' to classloader
   [junit4]   2> 679364 T2552 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
   [junit4]   2> 679399 T2552 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 679401 T2552 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 679408 T2552 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 679797 T2552 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 679798 T2552 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 679798 T2552 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 679805 T2552 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 679809 T2552 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 679821 T2552 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 679825 T2552 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 679829 T2552 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 679831 T2552 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 679831 T2552 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 679831 T2552 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 679832 T2552 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 679833 T2552 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 679833 T2552 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 679834 T2552 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381239936002/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/control/data/
   [junit4]   2> 679834 T2552 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b93cff75
   [junit4]   2> 679835 T2552 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/control/data
   [junit4]   2> 679835 T2552 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/control/data/index/
   [junit4]   2> 679836 T2552 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/control/data/index' doesn't exist. Creating new index...
   [junit4]   2> 679836 T2552 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/control/data/index
   [junit4]   2> 679837 T2552 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1481589676, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 679838 T2552 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@cc4f8e48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@48bcf2e8),segFN=segments_1,generation=1}
   [junit4]   2> 679838 T2552 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679841 T2552 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 679841 T2552 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 679841 T2552 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 679842 T2552 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 679842 T2552 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 679843 T2552 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 679844 T2552 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 679844 T2552 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 679845 T2552 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 679845 T2552 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 679846 T2552 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 679846 T2552 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 679846 T2552 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 679847 T2552 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 679847 T2552 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 679849 T2552 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 679852 T2552 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 679852 T2552 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 679853 T2552 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=7.236328125, floorSegmentMB=0.8935546875, forceMergeDeletesPctAllowed=5.808745657139683, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15841933989156748
   [junit4]   2> 679854 T2552 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@cc4f8e48 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@48bcf2e8),segFN=segments_1,generation=1}
   [junit4]   2> 679854 T2552 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679855 T2552 oass.SolrIndexSearcher.<init> Opening Searcher@af75219 main
   [junit4]   2> 679857 T2553 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@af75219 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 679857 T2552 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 679860 T2552 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:36807 collection:control_collection shard:shard1
   [junit4]   2> 679860 T2552 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
   [junit4]   2> 679862 T2552 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 679868 T2552 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 679870 T2552 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 679870 T2552 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 679871 T2552 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:36807/collection1/
   [junit4]   2> 679872 T2552 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 679872 T2552 oasc.SyncStrategy.syncToMe http://127.0.0.1:36807/collection1/ has no replicas
   [junit4]   2> 679873 T2552 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:36807/collection1/ shard1
   [junit4]   2> 679873 T2552 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 680385 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 680390 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 680428 T2552 oasc.ZkController.register We are http://127.0.0.1:36807/collection1/ and leader is http://127.0.0.1:36807/collection1/
   [junit4]   2> 680428 T2552 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36807
   [junit4]   2> 680429 T2552 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 680429 T2552 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 680429 T2552 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 680431 T2552 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 680433 T2527 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 680434 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 680435 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 680436 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:
   [junit4]   2> 680438 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 680441 T2556 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a4850e83 name:ZooKeeperConnection Watcher:127.0.0.1:43378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 680441 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 680442 T2527 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
   [junit4]   2> 680447 T2527 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
   [junit4]   2> 680537 T2527 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 680540 T2527 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:35615
   [junit4]   2> 680541 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 680542 T2527 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 680542 T2527 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391
   [junit4]   2> 680543 T2527 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/'
   [junit4]   2> 680551 T2527 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/solr.xml
   [junit4]   2> 680583 T2527 oasc.CoreContainer.<init> New CoreContainer -1254551239
   [junit4]   2> 680584 T2527 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/]
   [junit4]   2> 680585 T2527 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 680586 T2527 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 680587 T2527 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 680587 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 680588 T2527 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 680589 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 680589 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 680590 T2527 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 680590 T2527 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 680591 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 680592 T2527 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 680593 T2527 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 680594 T2527 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43378/solr
   [junit4]   2> 680594 T2527 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 680595 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 680597 T2567 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@197538b name:ZooKeeperConnection Watcher:127.0.0.1:43378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 680597 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 680599 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 680601 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 680603 T2569 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3438d4f6 name:ZooKeeperConnection Watcher:127.0.0.1:43378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 680604 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 680607 T2527 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
   [junit4]   2> 681610 T2527 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35615_
   [junit4]   2> 681612 T2527 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:35615_
   [junit4]   2> 681615 T2556 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 681616 T2549 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 681619 T2569 oascc.ZkStateReader$3.process Updating live nodes... (2)
   [junit4]   2> 681622 T2570 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 681622 T2570 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 681623 T2570 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 681893 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 681894 T2550 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
   [junit4]   2>  "operation":"state",
   [junit4]   2>  "state":"active",
   [junit4]   2>  "base_url":"http://127.0.0.1:36807",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:36807_",
   [junit4]   2>  "shard":"shard1",
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"control_collection",
   [junit4]   2>  "numShards":"1",
   [junit4]   2>  "core_node_name":"core_node1"}
   [junit4]   2> 681897 T2550 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:35615",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:35615_",
   [junit4]   2>  "shard":null,
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"collection1",
   [junit4]   2>  "numShards":"2",
   [junit4]   2>  "core_node_name":"core_node1"}
   [junit4]   2> 681897 T2550 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
   [junit4]   2> 681897 T2550 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 681900 T2556 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 681900 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 681900 T2569 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 682624 T2570 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 682624 T2570 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/collection1
   [junit4]   2> 682625 T2570 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 682626 T2570 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 682626 T2570 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 682627 T2570 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/collection1/'
   [junit4]   2> 682628 T2570 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/collection1/lib/classes/' to classloader
   [junit4]   2> 682629 T2570 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/collection1/lib/README' to classloader
   [junit4]   2> 682676 T2570 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
   [junit4]   2> 682726 T2570 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 682727 T2570 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 682734 T2570 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 683103 T2570 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 683103 T2570 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 683104 T2570 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 683110 T2570 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 683112 T2570 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 683123 T2570 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 683128 T2570 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 683133 T2570 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 683134 T2570 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 683134 T2570 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 683135 T2570 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 683136 T2570 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 683136 T2570 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 683136 T2570 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 683137 T2570 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381239939391/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty1/
   [junit4]   2> 683137 T2570 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b93cff75
   [junit4]   2> 683138 T2570 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty1
   [junit4]   2> 683138 T2570 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty1/index/
   [junit4]   2> 683139 T2570 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty1/index' doesn't exist. Creating new index...
   [junit4]   2> 683139 T2570 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty1/index
   [junit4]   2> 683140 T2570 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1481589676, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 683141 T2570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12cd670a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e4930423),segFN=segments_1,generation=1}
   [junit4]   2> 683141 T2570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 683143 T2570 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 683144 T2570 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 683145 T2570 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 683145 T2570 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 683146 T2570 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 683146 T2570 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 683147 T2570 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 683147 T2570 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 683147 T2570 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 683148 T2570 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 683149 T2570 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 683149 T2570 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 683150 T2570 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 683150 T2570 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 683151 T2570 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 683153 T2570 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 683156 T2570 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 683157 T2570 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 683157 T2570 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=7.236328125, floorSegmentMB=0.8935546875, forceMergeDeletesPctAllowed=5.808745657139683, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15841933989156748
   [junit4]   2> 683158 T2570 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12cd670a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e4930423),segFN=segments_1,generation=1}
   [junit4]   2> 683159 T2570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 683159 T2570 oass.SolrIndexSearcher.<init> Opening Searcher@b610d620 main
   [junit4]   2> 683162 T2571 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b610d620 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 683164 T2570 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 683164 T2570 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:35615 collection:collection1 shard:shard1
   [junit4]   2> 683165 T2570 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
   [junit4]   2> 683166 T2570 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
   [junit4]   2> 683172 T2570 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
   [junit4]   2> 683175 T2570 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 683176 T2570 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 683176 T2570 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:35615/collection1/
   [junit4]   2> 683176 T2570 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 683177 T2570 oasc.SyncStrategy.syncToMe http://127.0.0.1:35615/collection1/ has no replicas
   [junit4]   2> 683177 T2570 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:35615/collection1/ shard1
   [junit4]   2> 683177 T2570 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
   [junit4]   2> 683404 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 683410 T2556 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 683411 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 683410 T2569 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
   [junit4]   2> 683433 T2570 oasc.ZkController.register We are http://127.0.0.1:35615/collection1/ and leader is http://127.0.0.1:35615/collection1/
   [junit4]   2> 683433 T2570 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:35615
   [junit4]   2> 683434 T2570 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 683434 T2570 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 683434 T2570 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 683436 T2570 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 683437 T2527 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 683438 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 683439 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 683521 T2527 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 683523 T2527 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34569
   [junit4]   2> 683523 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 683524 T2527 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 683525 T2527 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383
   [junit4]   2> 683525 T2527 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/'
   [junit4]   2> 683535 T2527 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/solr.xml
   [junit4]   2> 683595 T2527 oasc.CoreContainer.<init> New CoreContainer 1338406309
   [junit4]   2> 683596 T2527 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/]
   [junit4]   2> 683599 T2527 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 683600 T2527 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 683601 T2527 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 683602 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 683603 T2527 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 683604 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 683605 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 683606 T2527 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 683607 T2527 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 683609 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 683610 T2527 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 683611 T2527 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 683613 T2527 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43378/solr
   [junit4]   2> 683614 T2527 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 683616 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 683617 T2583 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@344e561d name:ZooKeeperConnection Watcher:127.0.0.1:43378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 683618 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 683620 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 683623 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 683624 T2585 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8cb77273 name:ZooKeeperConnection Watcher:127.0.0.1:43378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 683624 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 683627 T2527 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
   [junit4]   2> 684632 T2527 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34569_
   [junit4]   2> 684633 T2527 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34569_
   [junit4]   2> 684635 T2569 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 684636 T2556 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 684636 T2549 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 684635 T2585 oascc.ZkStateReader$3.process Updating live nodes... (3)
   [junit4]   2> 684640 T2586 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 684641 T2586 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 684642 T2586 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 684917 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 684919 T2550 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2>  "operation":"state",
   [junit4]   2>  "state":"active",
   [junit4]   2>  "base_url":"http://127.0.0.1:35615",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:35615_",
   [junit4]   2>  "shard":"shard1",
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"collection1",
   [junit4]   2>  "numShards":"2",
   [junit4]   2>  "core_node_name":"core_node1"}
   [junit4]   2> 684926 T2550 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:34569",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:34569_",
   [junit4]   2>  "shard":null,
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"collection1",
   [junit4]   2>  "numShards":"2",
   [junit4]   2>  "core_node_name":"core_node2"}
   [junit4]   2> 684926 T2550 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 684927 T2550 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
   [junit4]   2> 684932 T2556 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 684932 T2585 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 684932 T2569 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 684933 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 685643 T2586 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 685643 T2586 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/collection1
   [junit4]   2> 685644 T2586 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 685645 T2586 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 685645 T2586 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 685646 T2586 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/collection1/'
   [junit4]   2> 685647 T2586 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/collection1/lib/classes/' to classloader
   [junit4]   2> 685647 T2586 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/collection1/lib/README' to classloader
   [junit4]   2> 685681 T2586 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
   [junit4]   2> 685719 T2586 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 685720 T2586 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 685729 T2586 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 686076 T2586 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 686076 T2586 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 686077 T2586 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 686083 T2586 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 686085 T2586 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 686106 T2586 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 686111 T2586 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 686116 T2586 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 686117 T2586 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 686118 T2586 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 686118 T2586 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 686120 T2586 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 686121 T2586 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 686121 T2586 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 686122 T2586 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381239942383/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty2/
   [junit4]   2> 686122 T2586 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b93cff75
   [junit4]   2> 686123 T2586 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty2
   [junit4]   2> 686124 T2586 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty2/index/
   [junit4]   2> 686124 T2586 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty2/index' doesn't exist. Creating new index...
   [junit4]   2> 686125 T2586 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty2/index
   [junit4]   2> 686125 T2586 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1481589676, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 686126 T2586 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6e217fa6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cf36ed25),segFN=segments_1,generation=1}
   [junit4]   2> 686127 T2586 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 686129 T2586 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 686130 T2586 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 686131 T2586 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 686131 T2586 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 686132 T2586 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 686132 T2586 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 686133 T2586 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 686133 T2586 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 686134 T2586 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 686135 T2586 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 686136 T2586 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 686136 T2586 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 686137 T2586 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 686137 T2586 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 686138 T2586 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 686139 T2586 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 686142 T2586 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 686143 T2586 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 686143 T2586 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=7.236328125, floorSegmentMB=0.8935546875, forceMergeDeletesPctAllowed=5.808745657139683, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15841933989156748
   [junit4]   2> 686144 T2586 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6e217fa6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cf36ed25),segFN=segments_1,generation=1}
   [junit4]   2> 686145 T2586 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 686145 T2586 oass.SolrIndexSearcher.<init> Opening Searcher@f045c0c8 main
   [junit4]   2> 686150 T2587 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f045c0c8 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 686152 T2586 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 686152 T2586 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:34569 collection:collection1 shard:shard2
   [junit4]   2> 686152 T2586 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
   [junit4]   2> 686154 T2586 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
   [junit4]   2> 686158 T2586 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
   [junit4]   2> 686160 T2586 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
   [junit4]   2> 686161 T2586 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
   [junit4]   2> 686161 T2586 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34569/collection1/
   [junit4]   2> 686161 T2586 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
   [junit4]   2> 686162 T2586 oasc.SyncStrategy.syncToMe http://127.0.0.1:34569/collection1/ has no replicas
   [junit4]   2> 686162 T2586 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34569/collection1/ shard2
   [junit4]   2> 686163 T2586 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
   [junit4]   2> 686440 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 686446 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 686446 T2585 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 686446 T2569 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 686446 T2556 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
   [junit4]   2> 686466 T2586 oasc.ZkController.register We are http://127.0.0.1:34569/collection1/ and leader is http://127.0.0.1:34569/collection1/
   [junit4]   2> 686467 T2586 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34569
   [junit4]   2> 686467 T2586 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
   [junit4]   2> 686467 T2586 oasc.ZkController.publish publishing core=collection1 state=active
   [junit4]   2> 686468 T2586 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 686469 T2586 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 686470 T2527 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 686471 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 686471 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 686548 T2527 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 686550 T2527 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49185
   [junit4]   2> 686551 T2527 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 686552 T2527 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 686552 T2527 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416
   [junit4]   2> 686553 T2527 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/'
   [junit4]   2> 686561 T2527 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/solr.xml
   [junit4]   2> 686594 T2527 oasc.CoreContainer.<init> New CoreContainer -1142747170
   [junit4]   2> 686595 T2527 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/]
   [junit4]   2> 686597 T2527 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 686598 T2527 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 686599 T2527 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 686600 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 686601 T2527 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 686602 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 686602 T2527 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 686603 T2527 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 686604 T2527 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 686605 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 686606 T2527 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 686607 T2527 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 686608 T2527 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:43378/solr
   [junit4]   2> 686609 T2527 oasc.ZkController.checkChrootPath zkHost includes chroot
   [junit4]   2> 686610 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 686612 T2599 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e405bff name:ZooKeeperConnection Watcher:127.0.0.1:43378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 686613 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 686614 T2527 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
   [junit4]   2> 686616 T2527 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
   [junit4]   2> 686618 T2601 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@93a1efd4 name:ZooKeeperConnection Watcher:127.0.0.1:43378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 686618 T2527 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
   [junit4]   2> 686621 T2527 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
   [junit4]   2> 687623 T2527 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49185_
   [junit4]   2> 687626 T2527 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49185_
   [junit4]   2> 687628 T2601 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 687628 T2569 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 687629 T2549 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 687629 T2585 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 687629 T2556 oascc.ZkStateReader$3.process Updating live nodes... (4)
   [junit4]   2> 687635 T2602 oasc.ZkController.publish publishing core=collection1 state=down
   [junit4]   2> 687635 T2602 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 687636 T2602 oasc.ZkController.waitForCoreNodeName look for our core node name
   [junit4]   2> 687953 T2550 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 687954 T2550 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
   [junit4]   2>  "operation":"state",
   [junit4]   2>  "state":"active",
   [junit4]   2>  "base_url":"http://127.0.0.1:34569",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:34569_",
   [junit4]   2>  "shard":"shard2",
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"collection1",
   [junit4]   2>  "numShards":"2",
   [junit4]   2>  "core_node_name":"core_node2"}
   [junit4]   2> 687957 T2550 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:49185",
   [junit4]   2>  "core":"collection1",
   [junit4]   2>  "roles":null,
   [junit4]   2>  "node_name":"127.0.0.1:49185_",
   [junit4]   2>  "shard":null,
   [junit4]   2>  "shard_range":null,
   [junit4]   2>  "shard_state":"active",
   [junit4]   2>  "collection":"collection1",
   [junit4]   2>  "numShards":"2",
   [junit4]   2>  "core_node_name":"core_node3"}
   [junit4]   2> 687957 T2550 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
   [junit4]   2> 687958 T2550 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
   [junit4]   2> 687979 T2556 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 687979 T2585 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 687979 T2569 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 687979 T2549 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 687979 T2601 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
   [junit4]   2> 688637 T2602 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
   [junit4]   2> 688637 T2602 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/collection1
   [junit4]   2> 688638 T2602 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
   [junit4]   2> 688639 T2602 oasc.ZkController.createCollectionZkNode Collection zkNode exists
   [junit4]   2> 688639 T2602 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
   [junit4]   2> 688641 T2602 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/collection1/'
   [junit4]   2> 688642 T2602 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/collection1/lib/classes/' to classloader
   [junit4]   2> 688643 T2602 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/collection1/lib/README' to classloader
   [junit4]   2> 688697 T2602 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
   [junit4]   2> 688742 T2602 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 688743 T2602 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 688755 T2602 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 689181 T2602 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 689182 T2602 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 689183 T2602 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 689188 T2602 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 689191 T2602 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 689203 T2602 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 689210 T2602 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 689215 T2602 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 689216 T2602 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 689217 T2602 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 689217 T2602 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 689218 T2602 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 689219 T2602 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 689219 T2602 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 689220 T2602 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381239945416/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty3/
   [junit4]   2> 689220 T2602 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b93cff75
   [junit4]   2> 689221 T2602 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty3
   [junit4]   2> 689222 T2602 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty3/index/
   [junit4]   2> 689222 T2602 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty3/index' doesn't exist. Creating new index...
   [junit4]   2> 689223 T2602 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381239935832/jetty3/index
   [junit4]   2> 689223 T2602 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1481589676, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 689224 T2602 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@34d2cdd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c1dbe5e6),segFN=segments_1,generation=1}
   [junit4]   2> 689225 T2602 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 689228 T2602 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 689228 T2602 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 689229 T2602 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 689230 T2602 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 689230 T2602 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 689230 T2602 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 689231 T2602 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 689232 T2602 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 689232 T2602 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 689233 T2602 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 689233 T2602 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 689234 T2602 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 689234 T2602 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 689235 T2602 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 689235 T2602 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 689237 T2602 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 689239 T2602 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 689240 T2602 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 689240 T2602 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=7.236328125, floorSegmentMB=0.8935546875, forceMergeDeletesPctAllowed=5.808745657139683, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15841933989156748
   [junit4]   2> 689241 T2602 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@34d2cdd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c1dbe5e6),segFN=segments_1,generation=1}
   [junit4]   2> 689241 T2602 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 689242 T2602 oass.SolrIndexSearcher.<init> Opening Searcher@8ed56b03 main
   [junit4]   2> 689245 T2603 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8ed56b03 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 689246 T2602 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 689247 T2602 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49185 collection:collection1 shard:shard1
   [junit4]   2> 689247 T2602 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
   [junit4]   2> 689250 T2602 oasc.ZkController.register We are http://127.0.0.1:49185/collection1/ and leader is http://127.0.0.1:35615/collection1/
   [junit4]   2> 689250 T2602 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49185
   [junit4]   2> 689251 T2602 oasc.ZkController.checkRecovery Core needs to recover:collection1
   [junit4]   2> 689251 T2602 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
   [junit4]   2> ASYNC  NEW_CORE C971 name=collection1 org.apache.solr.core.SolrCore@c54b7f80 url=http://127.0.0.1:49185/collection1 node=127.0.0.1:49185_ C971_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:49185, core=collection1, node_name=127.0.0.1:49185_}
   [junit4]   2> 689251 T2604 C971 P49185 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
   [junit4]   2> 689252 T2602 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
   [junit4]   2> 689252 T2604 C971 P49185 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
   [junit4]   2> 689253 T2604 C971 P49185 oasc.ZkController.publish publishing core=collection1 state=recovering
   [junit4]   2> 689253 T2604 C971 P49185 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
   [junit4]   2> 689254 T2604 C971 P49185 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 689255 T2527 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 689256 T2527

[...truncated too long message...]

  1>               "replicas":{"core_node1":{
   [junit4]   1>                   "state":"active",
   [junit4]   1>                   "base_url":"http://127.0.0.1:35615",
   [junit4]   1>                   "core":"onenodecollectioncore",
   [junit4]   1>                   "roles":"none",
   [junit4]   1>                   "node_name":"127.0.0.1:35615_",
   [junit4]   1>                   "leader":"true"}}}},
   [junit4]   1>           "maxShardsPerNode":"1",
   [junit4]   1>           "router":{"name":"compositeId"},
   [junit4]   1>           "replicationFactor":"1"},
   [junit4]   1>         "collection1":{
   [junit4]   1>           "shards":{
   [junit4]   1>             "shard1":{
   [junit4]   1>               "range":"80000000-ffffffff",
   [junit4]   1>               "state":"active",
   [junit4]   1>               "replicas":{
   [junit4]   1>                 "core_node1":{
   [junit4]   1>                   "state":"active",
   [junit4]   1>                   "base_url":"http://127.0.0.1:35615",
   [junit4]   1>                   "core":"collection1",
   [junit4]   1>                   "node_name":"127.0.0.1:35615_",
   [junit4]   1>                   "leader":"true"},
   [junit4]   1>                 "core_node3":{
   [junit4]   1>                   "state":"active",
   [junit4]   1>                   "base_url":"http://127.0.0.1:49185",
   [junit4]   1>                   "core":"collection1",
   [junit4]   1>                   "node_name":"127.0.0.1:49185_"}}},
   [junit4]   1>             "shard2":{
   [junit4]   1>               "range":"0-7fffffff",
   [junit4]   1>               "state":"active",
   [junit4]   1>               "replicas":{
   [junit4]   1>                 "core_node2":{
   [junit4]   1>                   "state":"active",
   [junit4]   1>                   "base_url":"http://127.0.0.1:34569",
   [junit4]   1>                   "core":"collection1",
   [junit4]   1>                   "node_name":"127.0.0.1:34569_",
   [junit4]   1>                   "leader":"true"},
   [junit4]   1>                 "core_node4":{
   [junit4]   1>                   "state":"active",
   [junit4]   1>                   "base_url":"http://127.0.0.1:50002",
   [junit4]   1>                   "core":"collection1",
   [junit4]   1>                   "node_name":"127.0.0.1:50002_"}}}},
   [junit4]   1>           "maxShardsPerNode":"1",
   [junit4]   1>           "router":{"name":"compositeId"},
   [junit4]   1>           "replicationFactor":"1"}}
   [junit4]   1>  /zookeeper (1)
   [junit4]   1>  DATA:
   [junit4]   1>      
   [junit4]   1>
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=DF7480F8F63CBFF7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=el_GR -Dtests.timezone=Etc/GMT -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   28.0s J0 | BasicDistributedZk2Test.testDistribSearch <<<
   [junit4]    > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected content type application/octet-stream but got text/html;charset=ISO-8859-1. <html>
   [junit4]    > <head>
   [junit4]    > <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
   [junit4]    > <title>Error 404 Can not find: /onenodecollectioncore/update</title>
   [junit4]    > </head>
   [junit4]    > <body>
   [junit4]    > <h2>HTTP ERROR: 404</h2>
   [junit4]    > <p>Problem accessing /onenodecollectioncore/update. Reason:
   [junit4]    > <pre>    Can not find: /onenodecollectioncore/update</pre></p>
   [junit4]    > <hr /><i><small>Powered by Jetty://</small></i>
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    >                                                
   [junit4]    > </body>
   [junit4]    > </html>
   [junit4]    > at __randomizedtesting.SeedInfo.seed([DF7480F8F63CBFF7:5E920EE08163DFCB]:0)
   [junit4]    > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:427)
   [junit4]    > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
   [junit4]    > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
   [junit4]    > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
   [junit4]    > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
   [junit4]    > at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
   [junit4]    > at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:88)
   [junit4]    > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
   [junit4]    > at java.lang.Thread.run(Thread.java:780)
   [junit4]   2> 704853 T2527 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> 27971 T2526 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> 705840 T2618 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
   [junit4]   2> NOTE: test params are: codec=Lucene45: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=el_GR, timezone=Etc/GMT
   [junit4]   2> NOTE: Linux 3.8.0-31-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=3,free=28334288,total=155320320
   [junit4]   2> NOTE: All tests run in this JVM: [DirectUpdateHandlerOptimizeTest, DocValuesTest, TestSolrDeletionPolicy1, HdfsLockFactoryTest, ZkCLITest, UnloadDistributedZkTest, TestFieldTypeCollectionResource, DistributedSpellCheckComponentTest, ZkControllerTest, TestJmxMonitoredMap, SpatialFilterTest, ScriptEngineTest, TestReloadAndDeleteDocs, StatelessScriptUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, TestJoin, TestDynamicFieldResource, SliceStateUpdateTest, SchemaVersionSpecificBehaviorTest, TestSchemaSimilarityResource, TestPostingsSolrHighlighter, WordBreakSolrSpellCheckerTest, TestSchemaVersionResource, TestShardHandlerFactory, CSVRequestHandlerTest, SolrIndexSplitterTest, TestQueryUtils, BinaryUpdateRequestHandlerTest, TestLMJelinekMercerSimilarityFactory, TestBadConfig, TestRandomDVFaceting, TestReversedWildcardFilterFactory, TestPerFieldSimilarity, TestRealTimeGet, TestPseudoReturnFields, TestGroupingSearch, TestLMDirichletSimilarityFactory, TestWriterPerf, TestSolrIndexConfig, DateMathParserTest, CacheHeaderTest, ShowFileRequestHandlerTest, SystemInfoHandlerTest, MoreLikeThisHandlerTest, DocValuesMissingTest, TestRemoteStreaming, FieldMutatingUpdateProcessorTest, HdfsBasicDistributedZkTest, BJQParserTest, LeaderElectionTest, SynonymTokenizerTest, CopyFieldTest, TestCollationKeyRangeQueries, TestSolrXMLSerializer, PreAnalyzedUpdateProcessorTest, ShardRoutingTest, TestSuggestSpellingConverter, QueryParsingTest, TestManagedSchema, TestFieldResource, BadComponentTest, BadCopyFieldTest, EchoParamsTest, TestDocSet, TestReplicationHandler, HdfsCollectionsAPIDistributedZkTest, LoggingHandlerTest, TestSchemaResource, SolrInfoMBeanTest, TestBM25SimilarityFactory, TestPHPSerializedResponseWriter, AnalysisAfterCoreReloadTest, TestCoreContainer, TestSort, FastVectorHighlighterTest, UpdateRequestProcessorFactoryTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSystemIdResolver, SyncSliceTest, ExternalFileFieldSortTest, BasicDistributedZk2Test]
   [junit4] Completed on J0 in 28.99s, 1 test, 1 error <<< FAILURES!

[...truncated 515 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:421: 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/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:451: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 327 suites, 1453 tests, 1 error, 34 ignored (6 assumptions)

Total time: 46 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure




---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]