You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/10/11 17:54:55 UTC
[JENKINS] Lucene-Solr-trunk-Linux (32bit/ibm-j9-jdk7) - Build #
7840 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/7840/
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([CE1CBEAF61246775:4FFA30B7167B0749]: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 10197 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4] 2> 854598 T2102 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 854602 T2102 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1381506410164
[junit4] 2> 854603 T2102 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 854604 T2103 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 854704 T2102 oasc.ZkTestServer.run start zk server on port:41769
[junit4] 2> 854706 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 854794 T2109 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@df96927e name:ZooKeeperConnection Watcher:127.0.0.1:41769 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 854794 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 854795 T2102 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 854799 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 854801 T2111 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@277cb546 name:ZooKeeperConnection Watcher:127.0.0.1:41769/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 854801 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 854802 T2102 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 854804 T2102 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 854807 T2102 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 854809 T2102 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 854811 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 854812 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 854817 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 854817 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 854821 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 854822 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 854824 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 854825 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 854827 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 854828 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 854831 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 854832 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 854834 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 854835 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 854838 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 854838 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 854875 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 854876 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 854879 T2102 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 854880 T2102 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 854955 T2102 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 854959 T2102 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42741
[junit4] 2> 854959 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 854960 T2102 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 854961 T2102 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449
[junit4] 2> 854961 T2102 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/'
[junit4] 2> 854969 T2102 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/solr.xml
[junit4] 2> 855007 T2102 oasc.CoreContainer.<init> New CoreContainer 1104505224
[junit4] 2> 855008 T2102 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/]
[junit4] 2> 855009 T2102 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 855010 T2102 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 855010 T2102 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 855011 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 855011 T2102 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 855012 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 855013 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 855013 T2102 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 855014 T2102 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 855014 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 855016 T2102 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 855016 T2102 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 855017 T2102 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:41769/solr
[junit4] 2> 855018 T2102 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 855019 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 855020 T2122 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72b7c137 name:ZooKeeperConnection Watcher:127.0.0.1:41769 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 855020 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 855022 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 855023 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 855025 T2124 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cda7f12 name:ZooKeeperConnection Watcher:127.0.0.1:41769/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 855025 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 855027 T2102 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 855030 T2102 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 855033 T2102 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 855035 T2102 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42741_
[junit4] 2> 855036 T2102 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42741_
[junit4] 2> 855039 T2102 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 855044 T2102 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 855047 T2102 oasc.Overseer.start Overseer (id=90538404096704515-127.0.0.1:42741_-n_0000000000) starting
[junit4] 2> 855049 T2102 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 855053 T2126 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 855054 T2102 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 855056 T2102 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 855057 T2102 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 855060 T2125 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 855063 T2127 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 855064 T2127 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 855065 T2127 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 856563 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 856564 T2125 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:42741",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42741_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 856564 T2125 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 856564 T2125 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 856568 T2124 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> 857066 T2127 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 857066 T2127 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/collection1
[junit4] 2> 857066 T2127 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 857067 T2127 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 857067 T2127 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 857068 T2127 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/collection1/'
[junit4] 2> 857069 T2127 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/collection1/lib/classes/' to classloader
[junit4] 2> 857070 T2127 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/collection1/lib/README' to classloader
[junit4] 2> 857099 T2127 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 857130 T2127 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 857131 T2127 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 857138 T2127 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 857461 T2127 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 857466 T2127 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 857469 T2127 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 857480 T2127 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 857484 T2127 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 857487 T2127 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 857489 T2127 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 857489 T2127 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 857489 T2127 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 857490 T2127 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 857491 T2127 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 857491 T2127 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 857491 T2127 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1381506410449/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/control/data/
[junit4] 2> 857492 T2127 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1081509
[junit4] 2> 857493 T2127 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/control/data
[junit4] 2> 857493 T2127 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/control/data/index/
[junit4] 2> 857493 T2127 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 857494 T2127 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/control/data/index
[junit4] 2> 857494 T2127 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=22.6103515625, floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=15.846162116364908, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 857495 T2127 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4dc4b1d5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8a5e8609)),segFN=segments_1,generation=1}
[junit4] 2> 857495 T2127 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 857497 T2127 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 857498 T2127 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 857498 T2127 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 857498 T2127 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 857499 T2127 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 857499 T2127 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 857500 T2127 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 857500 T2127 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 857500 T2127 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 857501 T2127 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 857501 T2127 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 857502 T2127 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 857502 T2127 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 857502 T2127 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 857503 T2127 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 857504 T2127 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 857506 T2127 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 857507 T2127 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 857507 T2127 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=71.123046875, floorSegmentMB=2.0224609375, forceMergeDeletesPctAllowed=1.0903765392520715, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2904614007334757
[junit4] 2> 857508 T2127 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4dc4b1d5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8a5e8609)),segFN=segments_1,generation=1}
[junit4] 2> 857508 T2127 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 857509 T2127 oass.SolrIndexSearcher.<init> Opening Searcher@ef854b09 main
[junit4] 2> 857511 T2128 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ef854b09 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 857511 T2127 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 857511 T2127 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42741 collection:control_collection shard:shard1
[junit4] 2> 857512 T2127 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 857513 T2127 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 857519 T2127 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 857520 T2127 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 857520 T2127 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 857521 T2127 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42741/collection1/
[junit4] 2> 857521 T2127 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 857522 T2127 oasc.SyncStrategy.syncToMe http://127.0.0.1:42741/collection1/ has no replicas
[junit4] 2> 857522 T2127 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42741/collection1/ shard1
[junit4] 2> 857522 T2127 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 858071 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 858077 T2124 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> 858127 T2127 oasc.ZkController.register We are http://127.0.0.1:42741/collection1/ and leader is http://127.0.0.1:42741/collection1/
[junit4] 2> 858128 T2127 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42741
[junit4] 2> 858128 T2127 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 858128 T2127 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 858128 T2127 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 858130 T2127 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 858131 T2102 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 858131 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 858132 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 858133 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 858135 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 858137 T2131 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15675a43 name:ZooKeeperConnection Watcher:127.0.0.1:41769/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 858137 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 858138 T2102 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 858141 T2102 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 858220 T2102 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 858223 T2102 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:39201
[junit4] 2> 858224 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 858225 T2102 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 858226 T2102 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702
[junit4] 2> 858227 T2102 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/'
[junit4] 2> 858240 T2102 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/solr.xml
[junit4] 2> 858281 T2102 oasc.CoreContainer.<init> New CoreContainer 670239013
[junit4] 2> 858281 T2102 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/]
[junit4] 2> 858283 T2102 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 858283 T2102 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 858284 T2102 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 858284 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 858285 T2102 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 858285 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 858286 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 858287 T2102 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 858287 T2102 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 858288 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 858289 T2102 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 858289 T2102 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 858290 T2102 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:41769/solr
[junit4] 2> 858291 T2102 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 858292 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 858293 T2142 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@aae8f14b name:ZooKeeperConnection Watcher:127.0.0.1:41769 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 858293 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 858295 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 858297 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 858298 T2144 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8b34b886 name:ZooKeeperConnection Watcher:127.0.0.1:41769/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 858299 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 858302 T2102 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 859305 T2102 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39201_
[junit4] 2> 859307 T2102 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39201_
[junit4] 2> 859310 T2131 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 859310 T2124 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 859310 T2144 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 859315 T2145 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 859315 T2145 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 859316 T2145 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 859581 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 859581 T2125 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:42741",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42741_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 859584 T2125 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:39201",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39201_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 859584 T2125 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 859585 T2125 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 859587 T2144 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> 859587 T2131 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> 859587 T2124 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> 860317 T2145 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 860318 T2145 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/collection1
[junit4] 2> 860318 T2145 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 860319 T2145 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 860319 T2145 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 860321 T2145 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/collection1/'
[junit4] 2> 860322 T2145 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/collection1/lib/classes/' to classloader
[junit4] 2> 860322 T2145 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/collection1/lib/README' to classloader
[junit4] 2> 860356 T2145 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 860387 T2145 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 860389 T2145 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 860395 T2145 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 860708 T2145 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 860713 T2145 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 860716 T2145 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 860726 T2145 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 860730 T2145 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 860733 T2145 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 860735 T2145 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 860735 T2145 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 860735 T2145 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 860736 T2145 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 860737 T2145 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 860737 T2145 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 860737 T2145 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1381506413702/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty1/
[junit4] 2> 860738 T2145 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1081509
[junit4] 2> 860739 T2145 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty1
[junit4] 2> 860739 T2145 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty1/index/
[junit4] 2> 860739 T2145 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 860740 T2145 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty1/index
[junit4] 2> 860740 T2145 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=22.6103515625, floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=15.846162116364908, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 860741 T2145 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3c3cfa64 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18755ac5)),segFN=segments_1,generation=1}
[junit4] 2> 860741 T2145 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 860744 T2145 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 860744 T2145 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 860745 T2145 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 860745 T2145 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 860745 T2145 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 860746 T2145 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 860746 T2145 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 860746 T2145 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 860747 T2145 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 860747 T2145 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 860748 T2145 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 860748 T2145 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 860749 T2145 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 860749 T2145 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 860750 T2145 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 860751 T2145 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 860753 T2145 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 860753 T2145 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 860754 T2145 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=71.123046875, floorSegmentMB=2.0224609375, forceMergeDeletesPctAllowed=1.0903765392520715, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2904614007334757
[junit4] 2> 860755 T2145 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3c3cfa64 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18755ac5)),segFN=segments_1,generation=1}
[junit4] 2> 860755 T2145 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 860756 T2145 oass.SolrIndexSearcher.<init> Opening Searcher@64bc332 main
[junit4] 2> 860759 T2146 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64bc332 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 860760 T2145 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 860761 T2145 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:39201 collection:collection1 shard:shard1
[junit4] 2> 860761 T2145 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 860762 T2145 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 860767 T2145 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 860769 T2145 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 860770 T2145 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 860770 T2145 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:39201/collection1/
[junit4] 2> 860770 T2145 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 860771 T2145 oasc.SyncStrategy.syncToMe http://127.0.0.1:39201/collection1/ has no replicas
[junit4] 2> 860771 T2145 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39201/collection1/ shard1
[junit4] 2> 860771 T2145 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 861092 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 861097 T2124 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> 861097 T2131 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> 861097 T2144 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> 861129 T2145 oasc.ZkController.register We are http://127.0.0.1:39201/collection1/ and leader is http://127.0.0.1:39201/collection1/
[junit4] 2> 861129 T2145 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39201
[junit4] 2> 861129 T2145 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 861130 T2145 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 861130 T2145 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 861132 T2145 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 861133 T2102 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 861134 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 861134 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 861198 T2102 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 861200 T2102 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58548
[junit4] 2> 861201 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 861201 T2102 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 861202 T2102 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696
[junit4] 2> 861202 T2102 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/'
[junit4] 2> 861211 T2102 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/solr.xml
[junit4] 2> 861241 T2102 oasc.CoreContainer.<init> New CoreContainer -1886337537
[junit4] 2> 861242 T2102 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/]
[junit4] 2> 861243 T2102 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 861244 T2102 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 861245 T2102 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 861245 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 861246 T2102 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 861246 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 861247 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 861247 T2102 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 861248 T2102 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 861249 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 861250 T2102 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 861250 T2102 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 861251 T2102 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:41769/solr
[junit4] 2> 861252 T2102 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 861253 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 861254 T2158 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63c13918 name:ZooKeeperConnection Watcher:127.0.0.1:41769 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 861255 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 861256 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 861257 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 861259 T2160 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@30764c34 name:ZooKeeperConnection Watcher:127.0.0.1:41769/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 861259 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 861262 T2102 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 862265 T2102 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58548_
[junit4] 2> 862266 T2102 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58548_
[junit4] 2> 862269 T2144 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 862269 T2124 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 862269 T2160 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 862269 T2131 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 862274 T2161 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 862274 T2161 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 862276 T2161 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 862602 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 862602 T2125 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:39201",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39201_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 862617 T2125 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:58548",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:58548_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 862618 T2125 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 862618 T2125 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 862622 T2124 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> 862622 T2144 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> 862622 T2131 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> 862622 T2160 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> 863276 T2161 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 863277 T2161 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/collection1
[junit4] 2> 863277 T2161 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 863278 T2161 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 863279 T2161 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 863280 T2161 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/collection1/'
[junit4] 2> 863281 T2161 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/collection1/lib/classes/' to classloader
[junit4] 2> 863282 T2161 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/collection1/lib/README' to classloader
[junit4] 2> 863319 T2161 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 863355 T2161 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 863356 T2161 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 863363 T2161 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 863781 T2161 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 863786 T2161 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 863789 T2161 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 863801 T2161 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 863805 T2161 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 863810 T2161 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 863811 T2161 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 863812 T2161 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 863813 T2161 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 863815 T2161 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 863816 T2161 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 863816 T2161 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 863817 T2161 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1381506416696/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty2/
[junit4] 2> 863818 T2161 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1081509
[junit4] 2> 863819 T2161 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty2
[junit4] 2> 863819 T2161 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty2/index/
[junit4] 2> 863820 T2161 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 863820 T2161 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty2/index
[junit4] 2> 863821 T2161 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=22.6103515625, floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=15.846162116364908, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 863822 T2161 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@117818e8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@361ec9e3)),segFN=segments_1,generation=1}
[junit4] 2> 863822 T2161 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 863824 T2161 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 863824 T2161 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 863825 T2161 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 863825 T2161 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 863826 T2161 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 863826 T2161 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 863826 T2161 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 863827 T2161 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 863827 T2161 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 863828 T2161 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 863828 T2161 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 863828 T2161 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 863829 T2161 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 863829 T2161 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 863830 T2161 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 863831 T2161 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 863833 T2161 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 863834 T2161 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 863834 T2161 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=71.123046875, floorSegmentMB=2.0224609375, forceMergeDeletesPctAllowed=1.0903765392520715, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2904614007334757
[junit4] 2> 863835 T2161 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@117818e8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@361ec9e3)),segFN=segments_1,generation=1}
[junit4] 2> 863835 T2161 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 863836 T2161 oass.SolrIndexSearcher.<init> Opening Searcher@b5a184c main
[junit4] 2> 863839 T2162 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b5a184c main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 863840 T2161 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 863840 T2161 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58548 collection:collection1 shard:shard2
[junit4] 2> 863841 T2161 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 863844 T2161 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 863851 T2161 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 863855 T2161 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 863856 T2161 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 863856 T2161 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58548/collection1/
[junit4] 2> 863856 T2161 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 863857 T2161 oasc.SyncStrategy.syncToMe http://127.0.0.1:58548/collection1/ has no replicas
[junit4] 2> 863857 T2161 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58548/collection1/ shard2
[junit4] 2> 863857 T2161 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 864126 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 864203 T2144 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> 864203 T2131 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> 864203 T2160 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> 864203 T2124 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> 864213 T2161 oasc.ZkController.register We are http://127.0.0.1:58548/collection1/ and leader is http://127.0.0.1:58548/collection1/
[junit4] 2> 864214 T2161 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58548
[junit4] 2> 864214 T2161 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 864214 T2161 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 864215 T2161 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 864216 T2161 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 864218 T2102 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 864219 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 864219 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 864313 T2102 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 864315 T2102 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55601
[junit4] 2> 864316 T2102 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 864317 T2102 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 864318 T2102 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782
[junit4] 2> 864318 T2102 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/'
[junit4] 2> 864332 T2102 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/solr.xml
[junit4] 2> 864375 T2102 oasc.CoreContainer.<init> New CoreContainer -1539316981
[junit4] 2> 864376 T2102 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/]
[junit4] 2> 864378 T2102 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 864378 T2102 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 864379 T2102 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 864379 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 864380 T2102 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 864381 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 864384 T2102 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 864385 T2102 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 864386 T2102 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 864387 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 864388 T2102 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 864389 T2102 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 864390 T2102 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:41769/solr
[junit4] 2> 864391 T2102 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 864392 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 864396 T2174 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f2c957f0 name:ZooKeeperConnection Watcher:127.0.0.1:41769 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 864396 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 864399 T2102 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 864401 T2102 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 864403 T2176 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b9dcd5f5 name:ZooKeeperConnection Watcher:127.0.0.1:41769/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 864403 T2102 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 864406 T2102 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 865409 T2102 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55601_
[junit4] 2> 865411 T2102 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55601_
[junit4] 2> 865413 T2176 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 865414 T2144 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 865414 T2124 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 865415 T2131 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 865414 T2160 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 865420 T2177 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 865421 T2177 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 865422 T2177 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 865709 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 865710 T2125 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:58548",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:58548_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 865713 T2125 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:55601",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55601_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 865714 T2125 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 865714 T2125 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 865719 T2176 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> 865719 T2160 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> 865719 T2144 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> 865719 T2124 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> 865721 T2131 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> 866422 T2177 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 866423 T2177 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/collection1
[junit4] 2> 866423 T2177 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 866425 T2177 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 866425 T2177 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 866427 T2177 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/collection1/'
[junit4] 2> 866428 T2177 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/collection1/lib/classes/' to classloader
[junit4] 2> 866428 T2177 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/collection1/lib/README' to classloader
[junit4] 2> 866465 T2177 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 866506 T2177 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 866507 T2177 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 866515 T2177 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 866963 T2177 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 866969 T2177 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 866971 T2177 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 866986 T2177 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 866990 T2177 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 866994 T2177 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 866996 T2177 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 866996 T2177 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 866996 T2177 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 866998 T2177 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 866998 T2177 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 866999 T2177 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 866999 T2177 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1381506419782/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty3/
[junit4] 2> 867000 T2177 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b1081509
[junit4] 2> 867001 T2177 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty3
[junit4] 2> 867001 T2177 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty3/index/
[junit4] 2> 867001 T2177 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 867002 T2177 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1381506410164/jetty3/index
[junit4] 2> 867002 T2177 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=22.6103515625, floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=15.846162116364908, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 867003 T2177 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3beb1e76 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23d355c3)),segFN=segments_1,generation=1}
[junit4] 2> 867003 T2177 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 867006 T2177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 867006 T2177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 867007 T2177 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 867007 T2177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 867007 T2177 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 867008 T2177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 867008 T2177 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 867009 T2177 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 867009 T2177 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 867010 T2177 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 867010 T2177 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 867011 T2177 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 867011 T2177 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 867012 T2177 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 867012 T2177 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 867014 T2177 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 867017 T2177 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 867017 T2177 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 867018 T2177 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=71.123046875, floorSegmentMB=2.0224609375, forceMergeDeletesPctAllowed=1.0903765392520715, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2904614007334757
[junit4] 2> 867019 T2177 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3beb1e76 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@23d355c3)),segFN=segments_1,generation=1}
[junit4] 2> 867019 T2177 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 867020 T2177 oass.SolrIndexSearcher.<init> Opening Searcher@c127a934 main
[junit4] 2> 867024 T2178 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c127a934 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 867025 T2177 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 867026 T2177 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55601 collection:collection1 shard:shard1
[junit4] 2> 867026 T2177 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 867029 T2177 oasc.ZkController.register We are http://127.0.0.1:55601/collection1/ and leader is http://127.0.0.1:39201/collection1/
[junit4] 2> 867030 T2177 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55601
[junit4] 2> 867031 T2177 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 867031 T2177 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C2670 name=collection1 org.apache.solr.core.SolrCore@f0a846a5 url=http://127.0.0.1:55601/collection1 node=127.0.0.1:55601_ C2670_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:55601, core=collectio
[...truncated too long message...]
1> "range":"80000000-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{"core_node1":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:39201",
[junit4] 1> "core":"onenodecollectioncore",
[junit4] 1> "roles":"none",
[junit4] 1> "node_name":"127.0.0.1:39201_",
[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:39201",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:39201_",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node3":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:55601",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:55601_"}}},
[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:58548",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:58548_",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node4":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:39291",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:39291_"}}}},
[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=CE1CBEAF61246775 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nl_NL -Dtests.timezone=Europe/Vilnius -Dtests.file.encoding=UTF-8
[junit4] ERROR 27.9s J1 | 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([CE1CBEAF61246775:4FFA30B7167B0749]: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> 882518 T2102 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 27926 T2101 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 882658 T2176 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Asserting, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=nl_NL, timezone=Europe/Vilnius
[junit4] 2> NOTE: Linux 3.8.0-31-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=2,free=94658440,total=229310464
[junit4] 2> NOTE: All tests run in this JVM: [TestCollationField, DeleteShardTest, ZkSolrClientTest, SimplePostToolTest, TestQuerySenderListener, TestRangeQuery, TestAtomicUpdateErrorCases, TimeZoneUtilsTest, TestDFRSimilarityFactory, TestInfoStreamLogging, TestSerializedLuceneMatchVersion, JSONWriterTest, SpellingQueryConverterTest, TestSolrXml, PreAnalyzedFieldTest, TestArbitraryIndexDir, TestCSVLoader, TestWordDelimiterFilterFactory, FullSolrCloudDistribCmdsTest, TestRandomFaceting, TestStressReorder, TestReload, HighlighterConfigTest, TestSolrXmlPersistor, ConvertedLegacyTest, TestOmitPositions, TestJmxIntegration, TestAnalyzeInfixSuggestions, TestStressRecovery, TestFiltering, TestDistributedSearch, TestAddFieldRealTimeGet, TestUtils, TestNumberUtils, HighlighterTest, TestSolrCoreProperties, SignatureUpdateProcessorFactoryTest, AlternateDirectoryTest, DirectUpdateHandlerTest, TestQueryTypes, HdfsBasicDistributedZk2Test, TestHashPartitioner, TestDefaultSimilarityFactory, TestRecovery, FieldAnalysisRequestHandlerTest, PrimUtilsTest, TestCodecSupport, HardAutoCommitTest, NotRequiredUniqueKeyTest, SolrCoreCheckLockOnStartupTest, TestQuerySenderNoQuery, BasicZkTest, TestSolrDeletionPolicy2, TestIndexSearcher, XsltUpdateRequestHandlerTest, TestDynamicFieldCollectionResource, IndexSchemaRuntimeFieldTest, PluginInfoTest, TestUpdate, PathHierarchyTokenizerFactoryTest, TestAnalyzedSuggestions, ChaosMonkeySafeLeaderTest, TestCloudManagedSchema, OpenCloseCoreStressTest, CollectionsAPIDistributedZkTest, TestXIncludeConfig, RequiredFieldsTest, HdfsLockFactoryTest, NumericFieldsTest, SortByFunctionTest, TestReloadAndDeleteDocs, IndexBasedSpellCheckerTest, TestIBSimilarityFactory, DistanceFunctionTest, SolrRequestParserTest, SolrIndexConfigTest, HdfsSyncSliceTest, UniqFieldsUpdateProcessorFactoryTest, XmlUpdateRequestHandlerTest, BasicDistributedZk2Test]
[junit4] Completed on J1 in 28.20s, 1 test, 1 error <<< FAILURES!
[...truncated 555 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:422: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:415: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:451: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1230: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:873: There were test failures: 326 suites, 1448 tests, 1 error, 34 ignored (6 assumptions)
Total time: 45 minutes 40 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